Bug 718331

Summary: Building x86_64 F15 Image fails on RHEVM/VMWARE
Product: [Retired] CloudForms Cloud Engine Reporter: scollier
Component: imagefactoryAssignee: Steve Linabery <slinaber>
Status: CLOSED CURRENTRELEASE QA Contact: wes hayutin <whayutin>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 0.3.1CC: akarol, dajohnso, deltacloud-maint, matt.wagner, morazi, sreichar, ssachdev, vvaldez
Target Milestone: rc   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description scollier 2011-07-01 20:03:55 UTC 
Description of problem:

On a clean install of aeolus F15 will not build with image factory.



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

Red Hat Enterprise Linux Server release 6.1 (Santiago)
Linux cf-cloudengine.cloud.lab.eng.bos.redhat.com 2.6.32-131.4.1.el6.x86_64 #1 SMP Fri Jun 10 10:54:26 EDT 2011 x86_64 x86_64 x86_64 GNU/Linux
postgresql-8.4.7-2.el6.x86_64
mongodb-1.6.4-3.el6_0.x86_64
euca2ools-1.3.1-4.el6_0.noarch
package gsoap is not installed
ruby-1.8.7.299-7.el6.x86_64
rubygems-1.3.7-1.el6.noarch
deltacloud-core-0.3.9999-1308927004.el6.noarch
rubygem-deltacloud-client-0.1.0-2.el6_0.noarch
libdeltacloud-0.9-1.el6.x86_64
hail-0.8-0.2.gf9c5b967.el6_0.x86_64
puppet-2.6.6-1.el6_0.noarch
aeolus-configure-2.0.1-0.el6.20110624145042git38b0c06.noarch
condor-7.6.0-4dcloud.el6.x86_64
iwhd-0.96.1.9e86-1.el6.x86_64
rubygem-image_factory_console-0.4.0-1.el6.20110627173747git2a88782.noarch
rubygem-image_factory_connector-0.0.3-1.el6.20110627173747git2a88782.noarch
imagefactory-0.2.2-1.el6.noarch
aeolus-conductor-daemons-0.3.0-0.el6.20110628135944git2a88782.noarch
aeolus-conductor-0.3.0-0.el6.20110628135944git2a88782.noarch


How reproducible:

easily.

Steps to Reproduce:
1. Use template:


<template>
  <name>fedora15_x86_64</name>
  <os>
    <name>Fedora</name>
    <version>15</version>
    <arch>x86_64</arch>
    <install type='iso'>
      <iso>http://refarch.cloud.lab.eng.bos.redhat.com/pub/kits/os/Fedora/15/x86_64/Fedora-15-x86_64-DVD.iso</iso>
    </install>
  </os>
  <description>My Fedora 15 x86_64 template</description>
</template>

with: 

aeolus-image build --target rhev-m --template /pub/projects/cloudforms/files/f15-temp.xml
  
Actual results:

2011-07-01 15:54:51,922 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting announcement to host
2011-07-01 15:54:51,924 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Removing icicle-nc binary
2011-07-01 15:54:51,924 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting crond service
2011-07-01 15:54:51,925 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Teardown step 3
2011-07-01 15:54:51,926 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting iptables rules
2011-07-01 15:54:51,926 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Teardown step 2
2011-07-01 15:54:51,926 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting sshd_config
2011-07-01 15:54:51,928 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting sshd service
2011-07-01 15:54:51,931 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Teardown step 1
2011-07-01 15:54:51,932 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Resetting authorized_keys
2011-07-01 15:54:51,934 INFO oz.Guest.FedoraGuest pid(3909) Message: Cleaning up guestfs handle for fedora15_x86_64
2011-07-01 15:54:51,934 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Syncing
2011-07-01 15:54:52,012 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Unmounting all
2011-07-01 15:54:52,044 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Killing guestfs subprocess
2011-07-01 15:54:52,044 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3909) Message: Exception caught in ImageFactory
2011-07-01 15:54:52,047 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3909) Message: Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 181, in build_upload
    self.output_descriptor = self.guest.generate_icicle(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 325, in generate_icicle
    self.collect_setup(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 288, in collect_setup
    self.image_ssh_teardown_step_4(g_handle)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 138, in image_ssh_teardown_step_4
    startuplink = self.get_service_runlevel_link(g_handle, 'crond')
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 75, in get_service_runlevel_link
    lines = g_handle.cat('/etc/init.d/' + service).split("\n")
  File "/usr/lib/python2.6/site-packages/guestfs.py", line 1262, in cat
    return libguestfsmod.cat (self._o, path)
RuntimeError: cat: open: /etc/init.d/crond: No such file or directory

2011-07-01 15:54:52,047 INFO oz.Guest.FedoraGuest pid(3909) Message: Cleaning up guest named fedora15_x86_64
2011-07-01 15:54:52,251 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3909) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED
2011-07-01 15:54:52,251 INFO oz.Guest.FedoraGuest pid(3909) Message: Cleaning up after install
2011-07-01 15:54:52,251 DEBUG oz.Guest.FedoraGuest pid(3909) Message: Removing modified ISO
2011-07-01 15:54:52,702 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3909) Message: Exception caught in ImageFactory
2011-07-01 15:54:52,703 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3909) Message: Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 125, in build_image
    self.build_upload(build_id)
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 181, in build_upload
    self.output_descriptor = self.guest.generate_icicle(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 325, in generate_icicle
    self.collect_setup(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 288, in collect_setup
    self.image_ssh_teardown_step_4(g_handle)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 138, in image_ssh_teardown_step_4
    startuplink = self.get_service_runlevel_link(g_handle, 'crond')
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 75, in get_service_runlevel_link
    lines = g_handle.cat('/etc/init.d/' + service).split("\n")
  File "/usr/lib/python2.6/site-packages/guestfs.py", line 1262, in cat
    return libguestfsmod.cat (self._o, path)
RuntimeError: cat: open: /etc/init.d/crond: No such file or directory

2011-07-01 15:54:52,703 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3909) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from FAILED to FAILED



Expected results:

image would build

Additional info:

N/A
Comment 1 Steve Reichard 2011-07-01 20:38:31 UTC 
Also seen doing 32-bit version of F15.

2011-07-01 16:35:22,941 DEBUG oz.Guest.FedoraGuest pid(31548) Message: Syncing
2011-07-01 16:35:23,041 DEBUG oz.Guest.FedoraGuest pid(31548) Message: Unmounting all
2011-07-01 16:35:23,070 DEBUG oz.Guest.FedoraGuest pid(31548) Message: Killing guestfs subprocess
2011-07-01 16:35:23,071 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(31548) Message: Exception caught in ImageFactory
2011-07-01 16:35:23,092 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(31548) Message: Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 181, in build_upload
    self.output_descriptor = self.guest.generate_icicle(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 325, in generate_icicle
    self.collect_setup(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 288, in collect_setup
    self.image_ssh_teardown_step_4(g_handle)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 138, in image_ssh_teardown_step_4
    startuplink = self.get_service_runlevel_link(g_handle, 'crond')
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 75, in get_service_runlevel_link
    lines = g_handle.cat('/etc/init.d/' + service).split("\n")
  File "/usr/lib/python2.6/site-packages/guestfs.py", line 1262, in cat
    return libguestfsmod.cat (self._o, path)
RuntimeError: cat: open: /etc/init.d/crond: No such file or directory

2011-07-01 16:35:23,092 INFO oz.Guest.FedoraGuest pid(31548) Message: Cleaning up guest named f15-i386
2011-07-01 16:35:23,314 DEBUG imagefactory.BuildJob.BuildAdaptor pid(31548) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED
2011-07-01 16:35:23,314 INFO oz.Guest.FedoraGuest pid(31548) Message: Cleaning up after install
2011-07-01 16:35:23,314 DEBUG oz.Guest.FedoraGuest pid(31548) Message: Removing modified ISO
2011-07-01 16:35:23,782 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(31548) Message: Exception caught in ImageFactory
2011-07-01 16:35:23,782 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(31548) Message: Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 125, in build_image
    self.build_upload(build_id)
  File "/usr/lib/python2.6/site-packages/imagefactory/builders/FedoraBuilder.py", line 181, in build_upload
    self.output_descriptor = self.guest.generate_icicle(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 325, in generate_icicle
    self.collect_setup(libvirt_xml)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 288, in collect_setup
    self.image_ssh_teardown_step_4(g_handle)
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 138, in image_ssh_teardown_step_4
    startuplink = self.get_service_runlevel_link(g_handle, 'crond')
  File "/usr/lib/python2.6/site-packages/oz/RedHat.py", line 75, in get_service_runlevel_link
    lines = g_handle.cat('/etc/init.d/' + service).split("\n")
  File "/usr/lib/python2.6/site-packages/guestfs.py", line 1262, in cat
    return libguestfsmod.cat (self._o, path)
RuntimeError: cat: open: /etc/init.d/crond: No such file or directory

2011-07-01 16:35:23,782 DEBUG imagefactory.BuildJob.BuildAdaptor pid(31548) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from FAILED to FAILED
Comment 2 Chris Lalancette 2011-07-05 13:23:52 UTC 
I'm fairly certain this will be solved by upgrading to oz 0.5.0, as there was a bug in 0.4.0 w.r.t. Fedora 15.

Chris Lalancette
Comment 3 Steve Linabery 2011-07-08 17:09:18 UTC 
Pushed new build of oz 0.5.0 to fedorapeople testing repos. Note that I haven't tested whether this actually fixes anything; putting this to ON_QA since that seems the most expedient way to get testing on RHEL.
Comment 4 wes hayutin 2011-07-08 18:48:37 UTC 
fyi.. to test this bug.. do not use.. ec2.
You'll run into https://bugzilla.redhat.com/show_bug.cgi?id=720026
Comment 5 wes hayutin 2011-07-08 21:06:29 UTC 










2011-07-08 16:51:37,497 DEBUG imagefactory.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(12241) Message: Method called: name = build_image 
 args = {'image': '', 'build': '', 'template': '<template>\n  <name>fedora15_postgres</name>\n  <os>    \n    <name>Fedora</name>    \n    <version>15</version>\n    <arch>x86_64</arch>\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  <description>Fedora 15 x86_64 with postgres</description>\n  <packages>\n    <package name=\'postgresql-server\'/> \n  </packages>\n</template>\n', 'targets': ['vmware']} 
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x21cf060> > 
 addr = redhat.com:imagefactory:6ca56077-0aac-4e8a-8c84-b2ecdeff1e98:image_factory 
 subtypes = {} 
 userId = anonymous
2011-07-08 16:51:37,498 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-07-08 16:51:37,513 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Creating a bucket returned status 500.  If only iwhd would provide a sane way to know if a bucket exists so we wouldn't have to try and create one every time...
2011-07-08 16:51:37,517 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'object_type': 'image', 'uuid': 'f6747c42-19ed-40a1-92e3-4140e47a2060'}) for http://localhost:9090/images/f6747c42-19ed-40a1-92e3-4140e47a2060
2011-07-08 16:51:37,597 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Creating a bucket returned status 500.  If only iwhd would provide a sane way to know if a bucket exists so we wouldn't have to try and create one every time...
2011-07-08 16:51:37,599 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'image': 'f6747c42-19ed-40a1-92e3-4140e47a2060', 'object_type': 'build', 'uuid': '9fcd461f-d2c1-48b4-a0b3-24abd13db30a'}) for http://localhost:9090/builds/9fcd461f-d2c1-48b4-a0b3-24abd13db30a
2011-07-08 16:51:37,721 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: libvirt bridge name is virbr0, host_bridge_ip is 192.168.122.1
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Name: fedora15_postgres, UUID: a724834e-4086-43eb-bde4-e25a01fc397f
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: MAC: 52:54:00:38:8f:41, distro: Fedora
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: update: 15, arch: x86_64, diskimage: /var/tmp/fedora15_postgres.dsk
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: nicmodel: virtio, clockoffset: utc
2011-07-08 16:51:37,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: mousetype: ps2, disk_bus: virtio, disk_dev: vda
2011-07-08 16:51:37,780 DEBUG oz.Guest.FedoraGuest pid(12241) Message: icicletmp: /var/lib/oz/icicletmp/fedora15_postgres, listen_port: 32399
2011-07-08 16:51:37,780 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Original ISO path: /var/lib/oz/isos/Fedora15x86_64-url.iso
2011-07-08 16:51:37,780 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Modified ISO cache: /var/lib/oz/isos/Fedora15x86_64-url-oz.iso
2011-07-08 16:51:37,780 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Output ISO path: /var/tmp/fedora15_postgres-url-oz.iso
2011-07-08 16:51:37,780 DEBUG oz.Guest.FedoraGuest pid(12241) Message: ISO content path: /var/lib/oz/isocontent/fedora15_postgres-url
2011-07-08 16:51:38,516 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Original URL http://download.fedoraproject.org/pub/fedora/linux/releases/15/Fedora/x86_64/os/ resolved to http://download.fedora.devel.redhat.com/pub/fedora/linux/releases/15/Fedora/x86_64/os/
2011-07-08 16:51:38,516 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: build_upload() called on FedoraBuilder...
2011-07-08 16:51:38,516 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Building for target vmware with warehouse config http://localhost:9090/
2011-07-08 16:51:38,517 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from NEW to BUILDING
2011-07-08 16:51:38,517 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up guest named fedora15_postgres
2011-07-08 16:51:38,519 INFO oz.Guest.FedoraGuest pid(12241) Message: Generating install media
2011-07-08 16:51:38,521 INFO oz.Guest.FedoraGuest pid(12241) Message: Fetching the original media
2011-07-08 16:51:40,713 INFO oz.Guest.FedoraGuest pid(12241) Message: Fetching the original install media from ftp://gromit.redhat.com//pub/fedora/linux/releases/15/Fedora/x86_64/os//images/boot.iso
2011-07-08 16:51:41,900 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 0kB of 203520kB
2011-07-08 16:51:50,076 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 10241kB of 203520kB
2011-07-08 16:51:59,138 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 20480kB of 203520kB
2011-07-08 16:52:06,152 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 30721kB of 203520kB
2011-07-08 16:52:11,345 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 40961kB of 203520kB
2011-07-08 16:52:15,534 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 51200kB of 203520kB
2011-07-08 16:52:21,611 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 61441kB of 203520kB
2011-07-08 16:52:27,332 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 71681kB of 203520kB
2011-07-08 16:52:31,962 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 81920kB of 203520kB
2011-07-08 16:52:35,874 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 92160kB of 203520kB
2011-07-08 16:52:39,699 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 102400kB of 203520kB
2011-07-08 16:52:45,307 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 112640kB of 203520kB
2011-07-08 16:52:48,749 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 122880kB of 203520kB
2011-07-08 16:52:51,946 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 133120kB of 203520kB
2011-07-08 16:52:55,306 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 143361kB of 203520kB
2011-07-08 16:52:58,646 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 153600kB of 203520kB
2011-07-08 16:53:01,918 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 163841kB of 203520kB
2011-07-08 16:53:05,189 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 174080kB of 203520kB
2011-07-08 16:53:08,493 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 184320kB of 203520kB
2011-07-08 16:53:11,783 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 194560kB of 203520kB
2011-07-08 16:53:14,702 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 203520kB of 203520kB
2011-07-08 16:53:14,702 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 203520kB of 203520kB
2011-07-08 16:53:14,702 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 203520kB of 203520kB
2011-07-08 16:53:14,703 DEBUG oz.Guest.FedoraGuest pid(12241) Message: 203520kB of 203520kB
2011-07-08 16:53:14,724 INFO oz.Guest.FedoraGuest pid(12241) Message: Copying ISO contents for modification
2011-07-08 16:53:14,725 INFO oz.Guest.FedoraGuest pid(12241) Message: Setting up guestfs handle for fedora15_postgres
2011-07-08 16:53:14,725 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Adding ISO image /var/lib/oz/isos/Fedora15x86_64-url.iso
2011-07-08 16:53:14,725 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Launching guestfs
2011-07-08 16:53:18,665 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Mounting ISO
2011-07-08 16:53:18,678 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Checking if there is enough space on the filesystem
2011-07-08 16:53:18,681 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Extracting ISO contents
2011-07-08 16:53:22,374 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Putting the kickstart in place
2011-07-08 16:53:22,404 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Modifying isolinux.cfg
2011-07-08 16:53:22,404 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Generating new ISO
2011-07-08 16:53:22,946 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up old ISO data
2011-07-08 16:53:22,994 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 0 to 10
2011-07-08 16:53:22,995 INFO oz.Guest.FedoraGuest pid(12241) Message: Generating 10GB diskimage for fedora15_postgres
2011-07-08 16:53:22,995 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Doing base install via Oz
2011-07-08 16:53:22,995 INFO oz.Guest.FedoraGuest pid(12241) Message: Running install for fedora15_postgres
2011-07-08 16:53:22,995 INFO oz.Guest.FedoraGuest pid(12241) Message: Generate XML for guest fedora15_postgres with bootdev cdrom
2011-07-08 16:53:23,059 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>fedora15_postgres</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>a724834e-4086-43eb-bde4-e25a01fc397f</uuid>
  <clock offset="utc"/>
  <vcpu>1</vcpu>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <os>
    <type>hvm</type>
    <boot dev="cdrom"/>
  </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:38:8f:41"/>
      <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/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk"/>
    </disk>
    <disk type="file" device="cdrom">
      <source file="/var/tmp/fedora15_postgres-url-oz.iso"/>
      <target dev="hdc"/>
    </disk>
  </devices>
</domain>

2011-07-08 16:53:24,884 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3600/3600
2011-07-08 16:53:34,909 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3590/3600
2011-07-08 16:53:44,934 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3580/3600
2011-07-08 16:53:54,962 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3570/3600
2011-07-08 16:54:04,987 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3560/3600
2011-07-08 16:54:15,021 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3550/3600
2011-07-08 16:54:25,048 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3540/3600
2011-07-08 16:54:35,076 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3530/3600
2011-07-08 16:54:45,105 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3520/3600
2011-07-08 16:54:55,134 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3510/3600
2011-07-08 16:55:05,162 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3500/3600
2011-07-08 16:55:15,189 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3490/3600
2011-07-08 16:55:25,212 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3480/3600
2011-07-08 16:55:35,324 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3470/3600
2011-07-08 16:55:45,351 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3460/3600
2011-07-08 16:55:55,377 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3450/3600
2011-07-08 16:56:05,404 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3440/3600
2011-07-08 16:56:15,431 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3430/3600
2011-07-08 16:56:25,457 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3420/3600
2011-07-08 16:56:35,484 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3410/3600
2011-07-08 16:56:45,510 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3400/3600
2011-07-08 16:56:55,537 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3390/3600
2011-07-08 16:57:05,562 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3380/3600
2011-07-08 16:57:15,589 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3370/3600
2011-07-08 16:57:25,617 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3360/3600
2011-07-08 16:57:35,644 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3350/3600
2011-07-08 16:57:45,668 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3340/3600
2011-07-08 16:57:55,694 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3330/3600
2011-07-08 16:58:05,717 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3320/3600
2011-07-08 16:58:15,739 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3310/3600
2011-07-08 16:58:25,765 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3300/3600
2011-07-08 16:58:35,791 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3290/3600
2011-07-08 16:58:45,818 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3280/3600
2011-07-08 16:58:55,846 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3270/3600
2011-07-08 16:59:05,874 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3260/3600
2011-07-08 16:59:15,898 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3250/3600
2011-07-08 16:59:25,920 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3240/3600
2011-07-08 16:59:35,945 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3230/3600
2011-07-08 16:59:45,969 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3220/3600
2011-07-08 16:59:55,994 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3210/3600
2011-07-08 17:00:06,016 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3200/3600
2011-07-08 17:00:16,042 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3190/3600
2011-07-08 17:00:26,069 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to finish installing, 3180/3600
2011-07-08 17:00:26,442 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Libvirt Domain Info Failed:
2011-07-08 17:00:26,442 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  code is 38
2011-07-08 17:00:26,442 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  domain is 10
2011-07-08 17:00:26,442 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  message is cannot send monitor command '{"execute":"query-blockstats"}': Connection reset by peer
2011-07-08 17:00:26,442 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  level is 2
2011-07-08 17:00:26,443 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str1 is %s
2011-07-08 17:00:26,443 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str2 is cannot send monitor command '{"execute":"query-blockstats"}': Connection reset by peer
2011-07-08 17:00:26,443 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str3 is None
2011-07-08 17:00:26,443 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int1 is -1
2011-07-08 17:00:26,443 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int2 is -1
2011-07-08 17:00:26,443 INFO oz.Guest.FedoraGuest pid(12241) Message: Install of fedora15_postgres succeeded
2011-07-08 17:00:26,443 INFO oz.Guest.FedoraGuest pid(12241) Message: Generate XML for guest fedora15_postgres with bootdev hd
2011-07-08 17:00:26,476 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>fedora15_postgres</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>a724834e-4086-43eb-bde4-e25a01fc397f</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:38:8f:41"/>
      <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/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk"/>
    </disk>
  </devices>
</domain>

2011-07-08 17:00:26,476 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Base install complete - Doing customization
2011-07-08 17:00:26,476 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 10 to 30
2011-07-08 17:00:26,492 INFO oz.Guest.FedoraGuest pid(12241) Message: Customizing image
2011-07-08 17:00:26,492 INFO oz.Guest.FedoraGuest pid(12241) Message: Collection Setup
2011-07-08 17:00:26,535 INFO oz.Guest.FedoraGuest pid(12241) Message: Setting up guestfs handle for fedora15_postgres
2011-07-08 17:00:26,536 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Adding disk image /var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk
2011-07-08 17:00:26,536 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Launching guestfs
2011-07-08 17:00:32,054 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Inspecting guest OS
2011-07-08 17:00:33,358 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Getting mountpoints
2011-07-08 17:00:33,359 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Root device: /dev/VolGroup00/LogVol00
2011-07-08 17:00:33,624 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 1: Uploading ssh keys
2011-07-08 17:00:33,686 INFO oz.Guest.FedoraGuest pid(12241) Message: Generating new openssh key
2011-07-08 17:00:33,687 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 2: setup sshd
2011-07-08 17:00:33,810 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 3: Open up the firewall
2011-07-08 17:00:33,810 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 4: Guest announcement
2011-07-08 17:00:33,879 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 5: Set SELinux to permissive mode
2011-07-08 17:00:33,885 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up guestfs handle for fedora15_postgres
2011-07-08 17:00:33,885 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Syncing
2011-07-08 17:00:34,044 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Unmounting all
2011-07-08 17:00:34,063 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Killing guestfs subprocess
2011-07-08 17:00:34,520 INFO oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot
2011-07-08 17:00:34,589 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 300/300
2011-07-08 17:00:44,622 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 290/300
2011-07-08 17:00:54,639 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 280/300
2011-07-08 17:01:04,656 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 270/300
2011-07-08 17:01:14,673 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 260/300
2011-07-08 17:01:24,688 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 250/300
2011-07-08 17:01:34,705 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 240/300
2011-07-08 17:01:44,720 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 230/300
2011-07-08 17:01:54,736 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 220/300
2011-07-08 17:02:03,225 DEBUG oz.Guest.FedoraGuest pid(12241) Message: IP address of guest is 192.168.122.26
2011-07-08 17:02:03,225 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Installing additional repository files
2011-07-08 17:02:03,225 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Installing custom packages
2011-07-08 17:02:35,779 INFO oz.Guest.FedoraGuest pid(12241) Message: Uploading custom files
2011-07-08 17:02:35,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Running custom commands
2011-07-08 17:02:35,779 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Syncing
2011-07-08 17:02:39,173 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to shutdown, 60/60
2011-07-08 17:02:44,183 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Libvirt Domain Info Failed:
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  code is 42
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  domain is 10
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  message is Domain not found: no domain with matching uuid 'a724834e-4086-43eb-bde4-e25a01fc397f'
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  level is 2
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str1 is Domain not found: %s
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str2 is no domain with matching uuid 'a724834e-4086-43eb-bde4-e25a01fc397f'
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str3 is None
2011-07-08 17:02:44,184 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int1 is -1
2011-07-08 17:02:44,185 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int2 is -1
2011-07-08 17:02:44,185 INFO oz.Guest.FedoraGuest pid(12241) Message: Collection Teardown
2011-07-08 17:02:44,186 INFO oz.Guest.FedoraGuest pid(12241) Message: Setting up guestfs handle for fedora15_postgres
2011-07-08 17:02:44,186 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Adding disk image /var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk
2011-07-08 17:02:44,186 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Launching guestfs
2011-07-08 17:02:47,630 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Inspecting guest OS
2011-07-08 17:02:49,014 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Getting mountpoints
2011-07-08 17:02:49,015 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Root device: /dev/VolGroup00/LogVol00
2011-07-08 17:02:49,341 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 1
2011-07-08 17:02:49,341 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting authorized_keys
2011-07-08 17:02:49,585 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 2
2011-07-08 17:02:49,585 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting sshd_config
2011-07-08 17:02:49,655 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting sshd service
2011-07-08 17:02:49,681 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 3
2011-07-08 17:02:49,681 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting iptables rules
2011-07-08 17:02:49,682 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 4
2011-07-08 17:02:49,682 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting announcement to host
2011-07-08 17:02:49,688 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Removing icicle-nc binary
2011-07-08 17:02:49,689 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting crond service
2011-07-08 17:02:49,720 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 5
2011-07-08 17:02:49,724 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up guestfs handle for fedora15_postgres
2011-07-08 17:02:49,724 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Syncing
2011-07-08 17:02:49,768 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Unmounting all
2011-07-08 17:02:49,782 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Killing guestfs subprocess
2011-07-08 17:02:49,842 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Customization complete
2011-07-08 17:02:49,842 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 30 to 50
2011-07-08 17:02:49,843 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Generating ICICLE
2011-07-08 17:02:49,843 INFO oz.Guest.FedoraGuest pid(12241) Message: Generating ICICLE
2011-07-08 17:02:49,843 INFO oz.Guest.FedoraGuest pid(12241) Message: Collection Setup
2011-07-08 17:02:49,845 INFO oz.Guest.FedoraGuest pid(12241) Message: Setting up guestfs handle for fedora15_postgres
2011-07-08 17:02:49,845 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Adding disk image /var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk
2011-07-08 17:02:49,845 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Launching guestfs
2011-07-08 17:02:53,277 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Inspecting guest OS
2011-07-08 17:02:54,611 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Getting mountpoints
2011-07-08 17:02:54,619 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Root device: /dev/VolGroup00/LogVol00
2011-07-08 17:02:54,963 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 1: Uploading ssh keys
2011-07-08 17:02:54,992 INFO oz.Guest.FedoraGuest pid(12241) Message: Generating new openssh key
2011-07-08 17:02:54,996 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 2: setup sshd
2011-07-08 17:02:55,081 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 3: Open up the firewall
2011-07-08 17:02:55,081 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 4: Guest announcement
2011-07-08 17:02:55,135 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Step 5: Set SELinux to permissive mode
2011-07-08 17:02:55,139 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up guestfs handle for fedora15_postgres
2011-07-08 17:02:55,139 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Syncing
2011-07-08 17:02:55,224 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Unmounting all
2011-07-08 17:02:55,236 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Killing guestfs subprocess
2011-07-08 17:02:55,683 INFO oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot
2011-07-08 17:02:55,694 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 300/300
2011-07-08 17:03:05,710 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 290/300
2011-07-08 17:03:15,727 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 280/300
2011-07-08 17:03:25,744 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 270/300
2011-07-08 17:03:35,758 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 260/300
2011-07-08 17:03:45,774 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 250/300
2011-07-08 17:03:55,788 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for guest fedora15_postgres to boot, 240/300
2011-07-08 17:04:03,035 DEBUG oz.Guest.FedoraGuest pid(12241) Message: IP address of guest is 192.168.122.26
2011-07-08 17:04:04,135 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Waiting for fedora15_postgres to shutdown, 60/60
2011-07-08 17:04:09,267 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Libvirt Domain Info Failed:
2011-07-08 17:04:09,267 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  code is 38
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  domain is 10
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  message is cannot send monitor command '{"execute":"query-balloon"}': Connection reset by peer
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  level is 2
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str1 is %s
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str2 is cannot send monitor command '{"execute":"query-balloon"}': Connection reset by peer
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  str3 is None
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int1 is -1
2011-07-08 17:04:09,268 DEBUG oz.Guest.FedoraGuest pid(12241) Message:  int2 is -1
2011-07-08 17:04:09,268 INFO oz.Guest.FedoraGuest pid(12241) Message: Collection Teardown
2011-07-08 17:04:09,269 INFO oz.Guest.FedoraGuest pid(12241) Message: Setting up guestfs handle for fedora15_postgres
2011-07-08 17:04:09,269 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Adding disk image /var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk
2011-07-08 17:04:09,269 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Launching guestfs
2011-07-08 17:04:12,758 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Inspecting guest OS
2011-07-08 17:04:13,984 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Getting mountpoints
2011-07-08 17:04:13,985 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Root device: /dev/VolGroup00/LogVol00
2011-07-08 17:04:14,295 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 1
2011-07-08 17:04:14,295 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting authorized_keys
2011-07-08 17:04:14,348 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 2
2011-07-08 17:04:14,348 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting sshd_config
2011-07-08 17:04:14,374 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting sshd service
2011-07-08 17:04:14,408 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 3
2011-07-08 17:04:14,408 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting iptables rules
2011-07-08 17:04:14,409 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 4
2011-07-08 17:04:14,409 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting announcement to host
2011-07-08 17:04:14,409 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Removing icicle-nc binary
2011-07-08 17:04:14,410 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Resetting crond service
2011-07-08 17:04:14,441 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Teardown step 5
2011-07-08 17:04:14,445 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up guestfs handle for fedora15_postgres
2011-07-08 17:04:14,445 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Syncing
2011-07-08 17:04:14,489 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Unmounting all
2011-07-08 17:04:14,507 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Killing guestfs subprocess
2011-07-08 17:04:14,555 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: ICICLE generation complete
2011-07-08 17:04:14,555 INFO oz.Guest.FedoraGuest pid(12241) Message: Cleaning up after install
2011-07-08 17:04:14,555 DEBUG oz.Guest.FedoraGuest pid(12241) Message: Removing modified ISO
2011-07-08 17:04:14,600 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Generated disk image (/var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk)
2011-07-08 17:04:14,600 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Adding cloud-info to local image
2011-07-08 17:04:14,600 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: init guestfs
2011-07-08 17:04:14,601 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: add input image
2011-07-08 17:04:14,601 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: launch guestfs
2011-07-08 17:04:18,302 INFO imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Creating cloud-info file indicating target (vmware)
2011-07-08 17:04:18,309 INFO imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Updating rc.local with Audrey conditional
2011-07-08 17:04:18,879 INFO imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Transforming image for use on VMWare
2011-07-08 17:04:18,879 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Converting raw kvm image (/var/tmp/base-image-fa63b555-04fb-456f-8e04-735e271b44dd.dsk) to vmware stream-optimized image (/var/tmp/vmware-image-fa63b555-04fb-456f-8e04-735e271b44dd.vmdk)
2011-07-08 17:05:35,351 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: VMWare stream conversion complete
2011-07-08 17:05:35,352 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Storing Fedora image at http://localhost:9090/...
2011-07-08 17:05:35,716 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Creating a bucket returned status 500.  If only iwhd would provide a sane way to know if a bucket exists so we wouldn't have to try and create one every time...
2011-07-08 17:05:35,977 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'object_type': 'template', 'uuid': '9d542a2c-c617-42a6-9e46-9568a0942bbd'}) for http://localhost:9090/templates/9d542a2c-c617-42a6-9e46-9568a0942bbd
2011-07-08 17:05:36,139 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Creating a bucket returned status 500.  If only iwhd would provide a sane way to know if a bucket exists so we wouldn't have to try and create one every time...
2011-07-08 17:05:36,210 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'object_type': 'icicle', 'uuid': '5d6bbe66-f075-4fc4-8cc0-053fa36980f3'}) for http://localhost:9090/icicles/5d6bbe66-f075-4fc4-8cc0-053fa36980f3
2011-07-08 17:05:36,251 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Creating a bucket returned status 500.  If only iwhd would provide a sane way to know if a bucket exists so we wouldn't have to try and create one every time...
2011-07-08 17:05:43,020 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'icicle': '5d6bbe66-f075-4fc4-8cc0-053fa36980f3', 'uuid': 'fa63b555-04fb-456f-8e04-735e271b44dd', 'template': '9d542a2c-c617-42a6-9e46-9568a0942bbd', 'target_parameters': 'No target parameters for cloud type vmware', 'object_type': 'target_image', 'target': 'vmware', 'build': '9fcd461f-d2c1-48b4-a0b3-24abd13db30a'}) for http://localhost:9090/target_images/fa63b555-04fb-456f-8e04-735e271b44dd
2011-07-08 17:05:43,262 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(12241) Message: Image warehouse storage complete
2011-07-08 17:05:43,262 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-07-08 17:05:43,263 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/f6747c42-19ed-40a1-92e3-4140e47a2060
2011-07-08 17:05:43,264 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/f6747c42-19ed-40a1-92e3-4140e47a2060
2011-07-08 17:05:43,265 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(12241) Message: Setting metadata ({'latest_unpushed': '9fcd461f-d2c1-48b4-a0b3-24abd13db30a'}) for http://localhost:9090/images/f6747c42-19ed-40a1-92e3-4140e47a2060
2011-07-08 17:05:43,267 DEBUG imagefactory.BuildJob.BuildAdaptor pid(12241) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to COMPLETED
Comment 6 wes hayutin 2011-07-11 00:30:21 UTC 
removing from tracker
Comment 7 wes hayutin 2011-08-01 19:56:19 UTC 
release pending...
Comment 8 wes hayutin 2011-08-01 19:57:51 UTC 
release pending...
Comment 10 wes hayutin 2011-12-08 13:54:39 UTC 
closing out old bugs
Comment 11 wes hayutin 2011-12-08 14:08:06 UTC 
perm close