I attemped to build/push/launch an image with a RHEV 3.0 provider. The logs indicate the
push has succeeded, but I do not see the image in the export domain in the RHEV-M 3.0
interface. When I attempt to deploy the image it is stuck in pending. Below are the
commands/configs/logs. Any tips on where to begin troubleshooting are appreciated.
# aeolus-image build --target rhevm --template template.tpl
# cat ~/template.tpl
<template>
<name>tmpl1</name>
<description>Fedora 14 Template</description>
<os>
<name>Fedora</name>
<arch>x86_64</arch>
<version>14</version>
<install type="url">
<url>http://10.0.3.41/fedora/</url>
</install>
</os>
# cat /var/www/html/deployable4.xml
<deployable name="Example">
<description>This is an Example Deployment</description>
<assemblies>
<assembly name="frontend" hwp="hwp1">
<image id="b42c5144-d62f-4a71-99e4-4b11925f9e2c"
build="09026658-71aa-4f23-b8b5-dd134141263e">
</image>
</assembly>
</assemblies>
</deployable>
# aeolus-image --images list
IMAGE ID LASTEST PUSHED BUILD NAME
OS OS VERSION ARCH DESCRIPTION
b42c5144-d62f-4a71-99e4-4b11925f9e2c 09026658-71aa-4f23-b8b5-dd134141263e
# ls -lRath /mnt/rhevm-nfs/
/mnt/rhevm-nfs/:
total 36K
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:16 provider_images
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 .
drwxr-xr-x. 5 nobody nobody 4.0K Oct 18 09:13 053b897b-cb68-4084-ba8e-835a23b43bad
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 target_images
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 icicles
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 templates
drwxr-x---. 2 nobody nobody 4.0K Oct 18 08:59 builds
drwxr-x---. 2 nobody nobody 4.0K Oct 18 08:59 images
drwxr-xr-x. 5 root root 4.0K Jul 27 05:12 ..
/mnt/rhevm-nfs/provider_images:
total 12K
-rw-r--r--. 1 nobody nobody 104 Oct 18 09:16 4b0bd044-57c6-4331-b0a2-1f9bf083f081
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:16 .
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad:
total 20K
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 09:16 images
drwxr-xr-x. 5 nobody nobody 4.0K Oct 18 09:13 .
drwxr-xr-x. 4 nobody nobody 4.0K Oct 18 05:04 master
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 05:02 dom_md
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad/images:
total 8.0K
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 09:16 .
drwxr-xr-x. 5 nobody nobody 4.0K Oct 18 09:13 ..
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad/master:
total 16K
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 09:16 vms
drwxr-xr-x. 5 nobody nobody 4.0K Oct 18 09:13 ..
drwxr-xr-x. 4 nobody nobody 4.0K Oct 18 05:04 .
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 05:04 tasks
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad/master/vms:
total 8.0K
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 09:16 .
drwxr-xr-x. 4 nobody nobody 4.0K Oct 18 05:04 ..
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad/master/tasks:
total 8.0K
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 05:04 .
drwxr-xr-x. 4 nobody nobody 4.0K Oct 18 05:04 ..
/mnt/rhevm-nfs/053b897b-cb68-4084-ba8e-835a23b43bad/dom_md:
total 16K
drwxr-xr-x. 5 nobody nobody 4.0K Oct 18 09:13 ..
-rw-r--r--. 1 nobody nobody 2.0K Oct 18 05:02 leases
drwxr-xr-x. 2 nobody nobody 4.0K Oct 18 05:02 .
-rw-r--r--. 1 nobody nobody 344 Oct 18 05:02 metadata
-rw-r--r--. 1 nobody nobody 16 Oct 18 05:02 outbox
-rw-r--r--. 1 nobody nobody 16 Oct 18 05:02 inbox
-rw-r--r--. 1 nobody nobody 8 Oct 18 05:02 ids
/mnt/rhevm-nfs/target_images:
total 11G
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
-rw-r--r--. 1 nobody nobody 10G Oct 18 09:07 e144fab2-96dc-43d6-93e1-610ca067465a
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 .
/mnt/rhevm-nfs/icicles:
total 32K
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
-rw-r--r--. 1 nobody nobody 22K Oct 18 09:05 1562b87a-b6d7-48da-8839-8ec27b850f0b
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 .
/mnt/rhevm-nfs/templates:
total 12K
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
-rw-r--r--. 1 nobody nobody 229 Oct 18 09:05 4f0ebeba-80ef-4086-879d-e6412368f6a1
drwxr-x---. 2 nobody nobody 4.0K Oct 18 09:05 .
/mnt/rhevm-nfs/builds:
total 8.0K
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
-rw-r--r--. 1 nobody nobody 0 Oct 18 08:59 09026658-71aa-4f23-b8b5-dd134141263e
drwxr-x---. 2 nobody nobody 4.0K Oct 18 08:59 .
-rw-r--r--. 1 nobody nobody 0 Oct 18 08:46 25df1ee4-43c6-4ff7-bb76-89db004580b8
-rw-r--r--. 1 nobody nobody 0 Oct 18 05:08 22dccdb4-fdf6-47ce-b687-446cf9ad3651
/mnt/rhevm-nfs/images:
total 20K
drwxr-xr-x. 9 nobody nobody 4.0K Oct 18 09:16 ..
-rw-r--r--. 1 nobody nobody 33 Oct 18 08:59 b42c5144-d62f-4a71-99e4-4b11925f9e2c
drwxr-x---. 2 nobody nobody 4.0K Oct 18 08:59 .
-rw-r--r--. 1 nobody nobody 33 Oct 18 08:46 ee3c3614-ecd1-40dd-957b-7ef01f64ca52
-rw-r--r--. 1 nobody nobody 33 Oct 18 05:08 361e9e19-6953-4a39-968f-0441f6c83fbf
# cat /etc/aeolus-configure/nodes/default_configure
#Default setup configuration.
#Set everything up on this box.
#You can override the default behavior
#by creating <fqdn>_configure with the
#class membership and parameters as
#desire and it will take precedence over this.
#NOTE: Although this suggests the components
#can be deployed on individual boxes. This will likely
#become a common practice, but be advised that currently
#apart from https on the web server for conductor, you should
#consider intermachine communications insecure. Securing
#intermachine service calls is on the roadmap.
---
parameters:
enable_https: true
enable_security: false
# Uncomment this section and provide reasonable values to
# enable RHEV values to be populated.
rhevm_nfs_server: 10.0.3.41
rhevm_nfs_export: /exportdomain
rhevm_nfs_mount_point: /mnt/rhevm-nfs
rhevm_deltacloud_port: 3005
# rhevm_deltacloud_username: rhevadmin(a)cloud.redhat.com
rhevm_deltacloud_username: admin(a)rhev3.cloud.redhat.com
rhevm_deltacloud_password: ***
rhevm_deltacloud_powershell_url:
https://rhev3.cloud.redhat.com:8443/api
#
# Uncomment this section and provide appropriate values to configure vmware
# vmware_api_endpoint:
vsphere.server.com
# vmware_username: username
# vmware_password: password
# vmware_datastore: datastore
# vmware_network_name: network_name
# vmware_deltacloud_port: 3006
classes:
- aeolus::conductor
- aeolus::image-factory
- aeolus::iwhd
- aeolus::conductor::seed_data
# Uncomment this section to include rhev setup
- aeolus::rhevm
#
# Uncomment this section to include vmware setup
#- aeolus::vmware
# /var/log/imagefactory.log
2011-10-18 09:00:04,465 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
<name>tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a</name>
<memory>1048576</memory>
<currentMemory>1048576</currentMemory>
<uuid>c2984bd1-eff6-4fa4-90bc-5a4785ac55fc</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:eb:23:37"/>
<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-e144fab2-96dc-43d6-93e1-610ca067465a.dsk"/>
</disk>
</devices>
</domain>
2011-10-18 09:00:04,465 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Base install complete - Doing customization
2011-10-18 09:00:04,465 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
percent complete from 10 to 30
2011-10-18 09:00:04,466 INFO oz.Guest.FedoraGuest pid(3586) Message: Customizing image
2011-10-18 09:00:04,466 INFO oz.Guest.FedoraGuest pid(3586) Message: No additional
packages, files or commands to install, skipping customization
2011-10-18 09:00:04,466 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Customization complete
2011-10-18 09:00:04,466 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
percent complete from 30 to 50
2011-10-18 09:00:04,466 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Generating ICICLE
2011-10-18 09:00:04,466 INFO oz.Guest.FedoraGuest pid(3586) Message: Generating ICICLE
2011-10-18 09:00:04,466 INFO oz.Guest.FedoraGuest pid(3586) Message: Collection Setup
2011-10-18 09:00:04,468 DEBUG oz.Guest.FedoraGuest pid(3586) Message: DomID: 1
2011-10-18 09:00:04,474 DEBUG oz.Guest.FedoraGuest pid(3586) Message: DomID: 2
2011-10-18 09:00:04,480 INFO oz.Guest.FedoraGuest pid(3586) Message: Setting up guestfs
handle for tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:00:04,480 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Adding disk image
/var/tmp/base-image-e144fab2-96dc-43d6-93e1-610ca067465a.dsk
2011-10-18 09:00:04,480 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Launching guestfs
2011-10-18 09:00:06,814 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Inspecting guest OS
2011-10-18 09:00:07,864 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Getting mountpoints
2011-10-18 09:00:07,864 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Root device:
/dev/VolGroup00/LogVol00
2011-10-18 09:00:08,034 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Step 1: Uploading
ssh keys
2011-10-18 09:00:08,037 INFO oz.Guest.FedoraGuest pid(3586) Message: Generating new
openssh key
2011-10-18 09:00:08,037 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Step 2: setup sshd
2011-10-18 09:00:08,061 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Step 3: Open up the
firewall
2011-10-18 09:00:08,062 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Step 4: Guest
announcement
2011-10-18 09:00:08,072 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Step 5: Set SELinux
to permissive mode
2011-10-18 09:00:08,077 INFO oz.Guest.FedoraGuest pid(3586) Message: Cleaning up guestfs
handle for tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:00:08,077 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Syncing
2011-10-18 09:00:08,105 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Unmounting all
2011-10-18 09:00:08,116 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Killing guestfs
subprocess
2011-10-18 09:00:08,667 INFO oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot
2011-10-18 09:00:08,677 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 300/300
2011-10-18 09:00:18,704 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 290/300
2011-10-18 09:00:28,725 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 280/300
2011-10-18 09:00:38,747 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 270/300
2011-10-18 09:00:48,768 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 260/300
2011-10-18 09:00:58,792 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for guest
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to boot, 250/300
2011-10-18 09:01:03,431 DEBUG oz.Guest.FedoraGuest pid(3586) Message: IP address of guest
is 192.168.122.60
2011-10-18 09:03:04,093 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Waiting for
tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a to shutdown, 60/60
2011-10-18 09:03:11,115 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Libvirt Domain Info
Failed:
2011-10-18 09:03:11,116 DEBUG oz.Guest.FedoraGuest pid(3586) Message: code is 42
2011-10-18 09:03:11,116 DEBUG oz.Guest.FedoraGuest pid(3586) Message: domain is 10
2011-10-18 09:03:11,116 DEBUG oz.Guest.FedoraGuest pid(3586) Message: message is Domain
not found: no domain with matching uuid 'c2984bd1-eff6-4fa4-90bc-5a4785ac55fc'
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: level is 2
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: str1 is Domain not
found: %s
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: str2 is no domain
with matching uuid 'c2984bd1-eff6-4fa4-90bc-5a4785ac55fc'
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: str3 is None
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: int1 is -1
2011-10-18 09:03:11,117 DEBUG oz.Guest.FedoraGuest pid(3586) Message: int2 is -1
2011-10-18 09:03:11,117 INFO oz.Guest.FedoraGuest pid(3586) Message: Collection Teardown
2011-10-18 09:03:11,118 DEBUG oz.Guest.FedoraGuest pid(3586) Message: DomID: 1
2011-10-18 09:03:11,120 DEBUG oz.Guest.FedoraGuest pid(3586) Message: DomID: 2
2011-10-18 09:03:11,122 INFO oz.Guest.FedoraGuest pid(3586) Message: Setting up guestfs
handle for tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:03:11,122 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Adding disk image
/var/tmp/base-image-e144fab2-96dc-43d6-93e1-610ca067465a.dsk
2011-10-18 09:03:11,122 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Launching guestfs
2011-10-18 09:03:13,476 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Inspecting guest OS
2011-10-18 09:03:14,477 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Getting mountpoints
2011-10-18 09:03:14,477 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Root device:
/dev/VolGroup00/LogVol00
2011-10-18 09:03:14,642 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Teardown step 1
2011-10-18 09:03:14,642 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting
authorized_keys
2011-10-18 09:03:14,653 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Teardown step 2
2011-10-18 09:03:14,653 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting
sshd_config
2011-10-18 09:03:14,659 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting sshd
service
2011-10-18 09:03:14,673 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Teardown step 3
2011-10-18 09:03:14,673 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting iptables
rules
2011-10-18 09:03:14,673 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Teardown step 4
2011-10-18 09:03:14,673 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting
announcement to host
2011-10-18 09:03:14,674 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Removing icicle-nc
binary
2011-10-18 09:03:14,674 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Resetting crond
service
2011-10-18 09:03:14,681 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Teardown step 5
2011-10-18 09:03:14,684 INFO oz.Guest.FedoraGuest pid(3586) Message: Cleaning up guestfs
handle for tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:03:14,684 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Syncing
2011-10-18 09:03:14,702 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Unmounting all
2011-10-18 09:03:14,713 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Killing guestfs
subprocess
2011-10-18 09:03:14,810 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: ICICLE generation complete
2011-10-18 09:03:14,810 INFO oz.Guest.FedoraGuest pid(3586) Message: Cleaning up after
install
2011-10-18 09:03:14,810 DEBUG oz.Guest.FedoraGuest pid(3586) Message: Removing modified
ISO
2011-10-18 09:03:14,854 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Generated disk image
(/var/tmp/base-image-e144fab2-96dc-43d6-93e1-610ca067465a.dsk)
2011-10-18 09:03:14,854 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Doing further Factory specific modification of Oz image
2011-10-18 09:03:14,854 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: init guestfs
2011-10-18 09:03:14,854 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: add input image
2011-10-18 09:03:14,854 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: launch guestfs
2011-10-18 09:03:17,325 INFO imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Creating cloud-info file indicating target (rhevm)
2011-10-18 09:03:17,326 INFO imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Updating rc.local with Audrey conditional
2011-10-18 09:03:18,334 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Removed HWADDR from image's /etc/sysconfig/network-scripts/ifcfg-eth0
2011-10-18 09:03:18,505 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Storing Fedora image at
http://localhost:9090/...
2011-10-18 09:03:18,512 INFO imagefactory.ImageWarehouse.ImageWarehouse pid(3586) Message:
Creating a bucket returned status 500.
2011-10-18 09:03:18,555 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'object_type': 'template', 'uuid':
'4f0ebeba-80ef-4086-879d-e6412368f6a1'}) for
http://localhost:9090/templates/4f0ebeba-80ef-4086-879d-e6412368f6a1
2011-10-18 09:03:18,634 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'object_type': 'icicle', 'uuid':
'1562b87a-b6d7-48da-8839-8ec27b850f0b'}) for
http://localhost:9090/icicles/1562b87a-b6d7-48da-8839-8ec27b850f0b
2011-10-18 09:05:17,961 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'icicle':
'1562b87a-b6d7-48da-8839-8ec27b850f0b', 'uuid':
'e144fab2-96dc-43d6-93e1-610ca067465a', 'template':
'4f0ebeba-80ef-4086-879d-e6412368f6a1', 'target_parameters': '<?xml
version="1.0"?>\n<domain type="kvm">\n
<name>tmpl1-e144fab2-96dc-43d6-93e1-610ca067465a</name>\n
<memory>1048576</memory>\n
<currentMemory>1048576</currentMemory>\n
<uuid>c2984bd1-eff6-4fa4-90bc-5a4785ac55fc</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:eb:23:37"/>\n <model
type="virtio"/>\n </interface>\n <input bus="ps2"
type="mouse"/>\n <console type="pty">\n <target
port="0"/>\n </console>\n <disk device="disk"
type="file">\n <target dev="vda"
bus="virtio"/>\n <source
file="/var/tmp/base-image-e144fab2-96dc-43d6-93e1-610ca067465a.dsk"/>\n
</disk>\n </devices>\n</domain>\n', 'object_type':
'target_image', 'target': 'rhevm', 'build':
'09026658-71aa-4f23-b8b5-dd134141263e'}) for
http://localhost:9090/target_images/e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:05:18,210 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Image warehouse storage complete
2011-10-18 09:05:18,210 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
percent complete from 50 to 100
2011-10-18 09:05:18,211 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Getting metadata (['latest_unpushed']) from
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:05:18,211 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Getting metadata (['latest_build']) from
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:05:18,213 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'latest_unpushed':
'09026658-71aa-4f23-b8b5-dd134141263e'}) for
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:05:18,214 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
status from BUILDING to COMPLETED
2011-10-18 09:07:16,106 DEBUG imagefactory.qmfagent.ImageFactoryAgent.ImageFactoryAgent
pid(3586) Message: Method called: name = push_image
args = {'credentials': '*** REDACTED ***', 'image':
'b42c5144-d62f-4a71-99e4-4b11925f9e2c', 'build': '',
'providers': ['rhevm']}
handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent
*' at 0x2b699c0> >
addr = redhat.com:imagefactory:81e453bd-892c-4487-83f3-8108e826a974:image_factory
subtypes = {}
userId = anonymous
2011-10-18 09:07:16,107 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Getting metadata (['latest_unpushed']) from
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:07:16,109 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Querying (
http://localhost:9090/target_images/_query) with expression ($build ==
"09026658-71aa-4f23-b8b5-dd134141263e" && $target == "rhevm")
2011-10-18 09:07:16,111 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Getting metadata (['template']) from
http://localhost:9090/target_images/e144fab2-96dc-43d6-93e1-610ca067465a
2011-10-18 09:07:16,112 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Created Image Warehouse instance
http://localhost:9090 - buckets(target_images,
templates, icicles, provider_images)
2011-10-18 09:07:16,151 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Created Image Warehouse instance
http://localhost:9090 - buckets(target_images,
templates, icicles, provider_images)
2011-10-18 09:07:16,154 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
status from NEW to PUSHING
2011-10-18 09:07:16,154 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
percent complete from 0 to 0
2011-10-18 09:14:23,203 DEBUG imagefactory.builders.BaseBuilder.FedoraBuilder pid(3586)
Message: Extracted RHEVM UUID: 97c827ed-d80f-4cdc-b41d-5a376d3d9afb
2011-10-18 09:14:23,369 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'target_image':
'e144fab2-96dc-43d6-93e1-610ca067465a', 'uuid':
'4b0bd044-57c6-4331-b0a2-1f9bf083f081', 'icicle': 'none',
'target_identifier': '97c827ed-d80f-4cdc-b41d-5a376d3d9afb',
'object_type': 'provider_image', 'provider': 'rhevm'}) for
http://localhost:9090/provider_images/4b0bd044-57c6-4331-b0a2-1f9bf083f081
2011-10-18 09:14:23,576 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
percent complete from 0 to 100
2011-10-18 09:14:23,576 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'latest_build':
'09026658-71aa-4f23-b8b5-dd134141263e'}) for
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:14:23,578 DEBUG imagefactory.ImageWarehouse.ImageWarehouse pid(3586)
Message: Setting metadata ({'latest_unpushed': None}) for
http://localhost:9090/images/b42c5144-d62f-4a71-99e4-4b11925f9e2c
2011-10-18 09:14:23,619 DEBUG imagefactory.BuildJob.BuildAdaptor pid(3586) Message:
Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed
status from PUSHING to COMPLETED
# /var/log/condor/GridmanagerLog.aeolus
10/18/11 12:33:23 [3066] BaseResource::UpdateResource:
Machine = "cldmgr01.cloud.redhat.com"
NumJobs = 1
IdleJobs = 1
RunningJobs = 0
SubmitsAllowed = 1
DeltacloudUserName = "admin(a)rhev3.cloud.redhat.com"
Name = "deltacloud
http://localhost:3005/api"
CondorVersion = "$CondorVersion: 7.6.0 Jun 30 2011 $"
ScheddIpAddr = "<10.0.4.21:54364>"
JobLimit = 1000
HashName =
"http://localhost:3005/api#admin@rhev3.cloud.redhat.com#/var/lib/aeolus-conductor/jobs/job_myDeployment100_frontend_1"
MyAddress = "<10.0.4.21:48310>"
GridResourceUnavailableTime = 1318955304
MyCurrentTime = 1318955603
SubmitsWanted = 0
CondorPlatform = "$CondorPlatform: X86_64-RedHat_6.1 $"
CurrentTime = time()
MyType = "Grid"
ScheddName = "cldmgr01.cloud.redhat.com"
Owner = "aeolus"
10/18/11 12:33:23 [3066] Trying to update collector <127.0.0.1:9618>
10/18/11 12:33:23 [3066] Attempting to send update via UDP to collector
localhost.localdomain <127.0.0.1:9618>
10/18/11 12:33:24 [3066] GAHP[3167] <- 'DELTACLOUD_VM_STATUS_ALL 186
http://localhost:3005/api admin(a)rhev3.cloud.redhat.com
/var/lib/aeolus-conductor/jobs/job_myDeployment100_frontend_1'
10/18/11 12:33:24 [3066] GAHP[3167] -> 'S'
10/18/11 12:33:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:33:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:33:24 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:33:24 [3066] GAHP[3167] -> 'R'
10/18/11 12:33:24 [3066] GAHP[3167] -> 'S' '1'
10/18/11 12:33:24 [3066] GAHP[3167] -> '186' 'Instance_Fetch_Failure:
undefined method `downcase' for nil:NilClass'
10/18/11 12:33:24 [3066] resource
http://localhost:3005/api is still down
10/18/11 12:33:35 [3066] Checking proxies
10/18/11 12:33:36 [3066] Evaluating periodic job policy expressions.
10/18/11 12:33:37 [3066] BaseResource::UpdateResource:
Machine = "cldmgr01.cloud.redhat.com"
NumJobs = 1
IdleJobs = 1
RunningJobs = 0
SubmitsAllowed = 1
DeltacloudUserName = "admin(a)rhev3.cloud.redhat.com"
Name = "deltacloud
http://localhost:3005/api"
CondorVersion = "$CondorVersion: 7.6.0 Jun 30 2011 $"
ScheddIpAddr = "<10.0.4.21:54364>"
JobLimit = 1000
HashName =
"http://localhost:3005/api#admin@rhev3.cloud.redhat.com#/var/lib/aeolus-conductor/jobs/job_TestX_frontend_1"
MyAddress = "<10.0.4.21:48310>"
GridResourceUnavailableTime = 1318928622
MyCurrentTime = 1318955617
SubmitsWanted = 0
CondorPlatform = "$CondorPlatform: X86_64-RedHat_6.1 $"
CurrentTime = time()
MyType = "Grid"
ScheddName = "cldmgr01.cloud.redhat.com"
Owner = "aeolus"
10/18/11 12:33:37 [3066] Trying to update collector <127.0.0.1:9618>
10/18/11 12:33:37 [3066] Attempting to send update via UDP to collector
localhost.localdomain <127.0.0.1:9618>
10/18/11 12:33:37 [3066] BaseResource::UpdateResource:
Machine = "cldmgr01.cloud.redhat.com"
NumJobs = 1
IdleJobs = 1
RunningJobs = 0
SubmitsAllowed = 1
DeltacloudUserName = "admin(a)rhev3.cloud.redhat.com"
Name = "deltacloud
http://localhost:3005/api"
CondorVersion = "$CondorVersion: 7.6.0 Jun 30 2011 $"
ScheddIpAddr = "<10.0.4.21:54364>"
JobLimit = 1000
HashName =
"http://localhost:3005/api#admin@rhev3.cloud.redhat.com#/var/lib/aeolus-conductor/jobs/job_testz_frontend_2"
MyAddress = "<10.0.4.21:48310>"
GridResourceUnavailableTime = 1318928617
MyCurrentTime = 1318955617
SubmitsWanted = 0
CondorPlatform = "$CondorPlatform: X86_64-RedHat_6.1 $"
CurrentTime = time()
MyType = "Grid"
ScheddName = "cldmgr01.cloud.redhat.com"
Owner = "aeolus"
10/18/11 12:33:37 [3066] Trying to update collector <127.0.0.1:9618>
10/18/11 12:33:37 [3066] Attempting to send update via UDP to collector
localhost.localdomain <127.0.0.1:9618>
10/18/11 12:33:37 [3066] GAHP[3167] <- 'DELTACLOUD_VM_STATUS_ALL 187
http://localhost:3005/api admin(a)rhev3.cloud.redhat.com
/var/lib/aeolus-conductor/jobs/job_testz_frontend_2'
10/18/11 12:33:37 [3066] GAHP[3167] -> 'S'
10/18/11 12:33:37 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:33:37 [3066] GAHP[3167] -> 'R'
10/18/11 12:33:37 [3066] GAHP[3167] -> 'S' '1'
10/18/11 12:33:37 [3066] GAHP[3167] -> '187' 'Invalid_Password_File'
10/18/11 12:33:37 [3066] resource
http://localhost:3005/api is still down
10/18/11 12:33:40 [3066] Received CHECK_LEASES signal
10/18/11 12:33:40 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:33:40 [3066] GAHP[3167] -> 'S' '0'
10/18/11 12:33:40 [3066] Evaluating staleness of remote job statuses.
10/18/11 12:33:40 [3066] in doContactSchedd()
10/18/11 12:33:40 [3066] querying for renewed leases
10/18/11 12:33:40 [3066] querying for removed/held jobs
10/18/11 12:33:40 [3066] Using constraint
((Owner=?="aeolus"&&JobUniverse==9)) && ((Managed =!=
"ScheddDone")) && (JobStatus == 3 || JobStatus == 4 || (JobStatus == 5
&& Managed =?= "External"))
10/18/11 12:33:40 [3066] Fetched 0 job ads from schedd
10/18/11 12:33:40 [3066] leaving doContactSchedd()
10/18/11 12:33:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:33:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:33:43 [3066] GAHP[3167] <- 'DELTACLOUD_VM_STATUS_ALL 188
http://localhost:3005/api admin(a)rhev3.cloud.redhat.com
/var/lib/aeolus-conductor/jobs/job_TestX_frontend_1'
10/18/11 12:33:43 [3066] GAHP[3167] -> 'S'
10/18/11 12:33:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:33:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:33:43 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:33:43 [3066] GAHP[3167] -> 'R'
10/18/11 12:33:43 [3066] GAHP[3167] -> 'S' '1'
10/18/11 12:33:43 [3066] GAHP[3167] -> '188' 'Invalid_Password_File'
10/18/11 12:33:43 [3066] resource
http://localhost:3005/api is still down
10/18/11 12:33:54 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:33:54 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:12 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:12 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:13 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:13 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:40 [3066] Received CHECK_LEASES signal
10/18/11 12:34:40 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:34:40 [3066] GAHP[3167] -> 'S' '0'
10/18/11 12:34:40 [3066] Evaluating staleness of remote job statuses.
10/18/11 12:34:40 [3066] in doContactSchedd()
10/18/11 12:34:40 [3066] querying for renewed leases
10/18/11 12:34:40 [3066] querying for removed/held jobs
10/18/11 12:34:40 [3066] Using constraint
((Owner=?="aeolus"&&JobUniverse==9)) && ((Managed =!=
"ScheddDone")) && (JobStatus == 3 || JobStatus == 4 || (JobStatus == 5
&& Managed =?= "External"))
10/18/11 12:34:40 [3066] Fetched 0 job ads from schedd
10/18/11 12:34:40 [3066] leaving doContactSchedd()
10/18/11 12:34:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:34:54 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:34:54 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:35:12 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:35:12 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:35:13 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:35:13 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:35:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:35:24 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:35:40 [3066] Received CHECK_LEASES signal
10/18/11 12:35:40 [3066] GAHP[3167] <- 'RESULTS'
10/18/11 12:35:40 [3066] GAHP[3167] -> 'S' '0'
10/18/11 12:35:40 [3066] Evaluating staleness of remote job statuses.
10/18/11 12:35:40 [3066] in doContactSchedd()
10/18/11 12:35:40 [3066] querying for renewed leases
10/18/11 12:35:40 [3066] querying for removed/held jobs
10/18/11 12:35:40 [3066] Using constraint
((Owner=?="aeolus"&&JobUniverse==9)) && ((Managed =!=
"ScheddDone")) && (JobStatus == 3 || JobStatus == 4 || (JobStatus == 5
&& Managed =?= "External"))
10/18/11 12:35:40 [3066] Fetched 0 job ads from schedd
10/18/11 12:35:40 [3066] leaving doContactSchedd()
10/18/11 12:35:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:35:42 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.
10/18/11 12:35:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api.
10/18/11 12:35:43 [3066] BaseResource::DoBatchStatus for
http://localhost:3005/api skipped
for 30 seconds because the resource is down.