Bug 710033

Summary: Building image from Image fails
Product: [Retired] CloudForms Cloud Engine Reporter: Jiri Tomasek <jtomasek>
Component: aeolus-conductorAssignee: Ian McLeod <imcleod>
Status: CLOSED CURRENTRELEASE QA Contact: wes hayutin <whayutin>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 0.3.1CC: dajohnso, deltacloud-maint, ssachdev, whayutin
Target Milestone: alpha   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of:
: 710094 (view as bug list) Environment:
Last Closed: 2011-12-08 13:46:54 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Bug Depends On:    
Bug Blocks: 710094    

Description Jiri Tomasek 2011-06-02 10:10:33 UTC 
Description of problem:
When building image via conductor, imagefactory creates new uuid for template instead of taking one sent from conductor. Image then doesng gen updated in conductor.
  

2011-06-02 09:18:23,865 DEBUG imagefactory.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(26176) Message: Method called: name = image
 args = {'target': 'ec2', 'template': 'http://localhost:9090/templates/74a742b8-8ce8-11e0-ac84-f0def13e79e3'}
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x1bceae0> >
 addr = redhat.com:imagefactory:00e11f60-590b-4029-a52b-6eb5f0def04d:image_factory
 subtypes = {}
 userId = anonymous
2011-06-02 09:18:23,879 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(26176) Message: Created Image Warehouse instance http://localhost:9090 - buckets(images, templates, icicles, provider_images)
2011-06-02 09:18:23,909 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(26176) Message: Created Image Warehouse instance http://localhost:9090 - buckets(images, templates, icicles, provider_images)
2011-06-02 09:18:23,934 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(26176) Message: Created Image Warehouse instance http://localhost:9090 - buckets(images, templates, icicles, provider_images)
2011-06-02 09:18:23,938 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(26176) Message: Building Linux for non-upload cloud (ec2)
2011-06-02 09:18:23,940 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(26176) Message: Storing placeholder object for non upload cloud image
2011-06-02 09:18:23,943 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(26176) 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-06-02 09:18:23,946 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(26176) Message: Setting metadata ({'object_type': 'template', 'uuid': 'e4365164-f46c-4907-9170-6f7e587831e9'}) for http://localhost:9090/templates/e4365164-f46c-4907-9170-6f7e587831e9





# curl http://localhost:9090/templates/e4365164-f46c-4907-9170-6f7e587831e9
<?xml version="1.0"?>
<template>
  <name>test1</name>
  <description>Template Description</description>
  <os>
    <name>Fedora</name>
    <arch>x86_64</arch>
    <version>13</version>
    <install type="url">
      <url>http://download.fedoraproject.org/pub/fedora/linux/releases/13/Fedora/x86_64/os/</url>
    </install>
  </os>
</template>
[root@dhcp-29-28 aeolus-conductor]# curl http://localhost:9090/templates/74a742b8-8ce8-11e0-ac84-f0def13e79e3
<?xml version="1.0"?>
<template>
  <name>test1</name>
  <description>Template Description</description>
  <os>
    <name>Fedora</name>
    <arch>x86_64</arch>
    <version>13</version>
    <install type="url">
      <url>http://download.fedoraproject.org/pub/fedora/linux/releases/13/Fedora/x86_64/os/</url>
    </install>
  </os>
</template>
Comment 1 Jiri Tomasek 2011-06-02 10:13:33 UTC 
Also tried with this patch:

diff --git a/imagefactory/builders/BaseBuilder.py b/imagefactory/builders/BaseBuilder.py
index b9546fa..11d5144 100644
--- a/imagefactory/builders/BaseBuilder.py
+++ b/imagefactory/builders/BaseBuilder.py
@@ -225,7 +225,10 @@ class BaseBuilder(object):
         raise NotImplementedError
 
     def store_image(self, target_parameters=None):
-        template_id = self.warehouse.store_template(self.template.xml, self.template.identifier)
+        if self.template.identifier:
+            template_id = self.template.identifier
+        else:
+            template_id = self.warehouse.store_template(self.template.xml, self.template.identifier)
         icicle_id = self.warehouse.store_icicle(self.output_descriptor)
         metadata = dict(template=template_id, target=self.target, icicle=icicle_id, target_parameters=target_parameters)
         self.warehouse.store_image(self.image_id, self.image, metadata=metadata)
Comment 2 wes hayutin 2011-06-15 12:12:52 UTC 
moving bugs to cloud engine project
Comment 3 wes hayutin 2011-06-16 18:20:45 UTC 
*** Bug 710094 has been marked as a duplicate of this bug. ***
Comment 4 wes hayutin 2011-06-16 18:36:16 UTC 
please recreate and paste build info
Comment 5 wes hayutin 2011-06-16 18:36:46 UTC 
see if we can move this to verified
Comment 6 wes hayutin 2011-07-05 18:33:35 UTC 
need to create
Comment 7 wes hayutin 2011-07-08 21:06:22 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 8 wes hayutin 2011-07-11 00:30:12 UTC 
removing from tracker
Comment 9 wes hayutin 2011-08-01 19:53:18 UTC 
release pending...
Comment 10 wes hayutin 2011-08-01 19:55:34 UTC 
release pending...
Comment 12 wes hayutin 2011-12-08 13:46:54 UTC 
perm close