755690 – Installing packages in EC2 image hangs

Bug 755690 - Installing packages in EC2 image hangs

Summary: Installing packages in EC2 image hangs

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

Reported:	2011-11-21 19:27 UTC by Jason Ganovsky
Modified:	2013-04-30 22:52 UTC (History)
CC List:	6 users (show)
Fixed In Version:
Doc Type:	Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed:	2011-11-29 22:38:48 UTC

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

Description Jason Ganovsky 2011-11-21 19:27:44 UTC

Description of problem: Installing packages from a 3rd party repo in EC2 images fails.  Using the same repo for VMware or RHEV images works.


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


How reproducible: Build template file (package web exists in the repo and is installable manually in running EC2 instances, just not automatically during the image build)

<template>
  <name>f14jeos</name>
  <os>
    <name>Fedora</name>
    <version>14</version>
    <arch>x86_64</arch>
    <install type='url'>
      <url>http://download.fedoraproject.org/pub/fedora/linux/releases/14/Fedora/x86_64/os/</url>
    </install>
    <rootpw>passwd</rootpw>
  </os>
  <description>Fedora 14</description>
  <repositories>
    <repository name="custom">
      <url>http://lnxservices.dlinkddns.com/cflab/</url>
      <signed>false</signed>
    </repository>
  </repositories>
  <packages>
   <package name='httpd'/>
   <package name='web'/>
  </packages>
</template>

After pushing with aeolus-image the build seems to hang and the last entry in imagefactory.log is

2011-11-21 14:12:44,915 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(3722) Message: Customizing guest: ec2-75-101-176-30.compute-1.amazonaws.com
2011-11-21 14:12:44,915 DEBUG oz.Guest.FedoraRemoteGuest pid(3722) Message: Installing additional repository files

Comment 1 Jason Ganovsky 2011-11-21 20:35:54 UTC

Also seems to occur in 0.6.0.  Commenting out the repositories (see below) and the web package allows the install to finish normally

<template>
  <name>f14jeos</name>
  <os>
    <name>Fedora</name>
    <version>14</version>
    <arch>x86_64</arch>
    <install type='url'>
     
<url>http://download.fedoraproject.org/pub/fedora/linux/releases/14/Fedora/x86_64/os/</url>
    </install>
    <rootpw>passwd</rootpw>
  </os>
  <description>Fedora 14</description>
  <!-- repositories>
    <repository name="custom">
      <url>http://lnxservices.dlinkddns.com/cflab/</url>
      <signed>false</signed>
    </repository>
  </repositories -->
  <packages>
   <package name='httpd'/>
   <!-- package name='web'/ -->
  </packages>
</template>

Comment 2 wes hayutin 2011-11-29 13:03:23 UTC

I'm able to resolve lnxservices.dlinkddns.com/cflab but not able to pull anything from port 80


[whayutin@localhost ~]$ nslookup lnxservices.dlinkddns.com
Server:		10.11.255.155
Address:	10.11.255.155#53

Non-authoritative answer:
Name:	lnxservices.dlinkddns.com
Address: 68.197.96.238

[whayutin@localhost ~]$ telnet 68.197.96.238 80
Trying 68.197.96.238...

Please double check the network config

Comment 3 Jason Ganovsky 2011-11-29 13:44:19 UTC

Sorry - I closed the port and shut down the server.  I'll bring it all back up in a little bit

Comment 4 jrd 2011-11-29 15:43:09 UTC

So according to the last comment, it sounds like this is "not a bug".  Concur?

Comment 5 wes hayutin 2011-11-29 17:00:17 UTC

[root@qeblade31 ~]# aeolus-cli build --target ec2 --template 755690.tpl 
Image: 9031ab80-36cd-4d04-b088-14eaa9c76ea0
Build: 5d7e49ec-4693-4430-a137-f92eac305887
Target Image: 79fa5e17-e84e-4a21-9817-fd4f94bff552	 :Status New
[root@qeblade31 ~]# ./getTemplateStatus.py 79fa5e17-e84e-4a21-9817-fd4f94bff552
mandatory option is missing

Usage: getTemplateStatus.py [options]

Options:
  -h, --help            show this help message and exit
  -i IMAGE, --image=IMAGE
                        the TARGET OR Provider image id
  -v, --verbose         additional logging
[root@qeblade31 ~]# ./getTemplateStatus.py -i 79fa5e17-e84e-4a21-9817-fd4f94bff552
COMPLETED
[root@qeblade31 ~]# aeolus-cli push --account ec2-us-east-1_AKIAJ557U7P7OIHRV2EQ --targetimage 79fa5e17-e84e-4a21-9817-fd4f94bff552
Target Image: 79fa5e17-e84e-4a21-9817-fd4f94bff552
Provider Image: 3100b50c-627a-4069-be3a-6dec1683180d	 Status: New
[root@qeblade31 ~]# cat 755690.tpl 
<template>
  <name>f14jeos</name>
  <os>
    <name>Fedora</name>
    <version>14</version>
    <arch>x86_64</arch>
    <install type='url'>
<url>http://download.fedoraproject.org/pub/fedora/linux/releases/14/Fedora/x86_64/os/</url>
    </install>
    <rootpw>passwd</rootpw>
  </os>
  <description>Fedora 14</description>
  <repositories>
    <repository name="custom">
      <url>http://lnxservices.dlinkddns.com/cflab/</url>
      <signed>false</signed>
    </repository>
  </repositories>
  <packages>
   <package name='httpd'/>
   <package name='web'/>
  </packages>
</template>
[root@qeblade31 ~]# 


-11-29 11:49:07,024 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: Output ISO path: /home/var/lib/imagefactory/images/f14jeos-3100b50c-627a-4069-be3a-6dec1683180d-url-oz.iso
2011-11-29 11:49:07,024 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: ISO content path: /home/var/lib/oz/isocontent/f14jeos-3100b50c-627a-4069-be3a-6dec1683180d-url
2011-11-29 11:49:07,024 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Being asked to push for provider ec2-us-east-1
2011-11-29 11:49:07,024 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: distro: Fedora - update: 14 - arch: x86_64
2011-11-29 11:49:07,059 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: acting as EC2 user: 6735-0069-5950
2011-11-29 11:49:07,059 DEBUG imgfac.BuildJob.BuildJob pid(7467) Message: Builder (3100b50c-627a-4069-be3a-6dec1683180d) changed status from NEW to PUSHING
2011-11-29 11:49:07,059 DEBUG imgfac.BuildJob.BuildJob pid(7467) Message: Builder (3100b50c-627a-4069-be3a-6dec1683180d) changed percent complete from 0 to 0
2011-11-29 11:49:07,060 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Starting ami ami-5b1dd932 with instance_type m1.large
2011-11-29 11:49:07,344 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Creating temporary security group (imagefactory-3100b50c-627a-4069-be3a-6dec1683180d)
2011-11-29 11:49:21,666 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Waiting for EC2 instance to start: 0/300
2011-11-29 11:49:32,681 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Waiting for EC2 instance to start: 10/300
2011-11-29 11:49:43,623 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Waiting for EC2 instance to start: 20/300
2011-11-29 11:49:43,731 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Waiting for EC2 ssh access: 0/300
2011-11-29 11:49:56,121 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Waiting 20 seconds for remaining boot tasks
2011-11-29 11:50:16,142 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Stopping cron and killing any updatedb process that may be running
2011-11-29 11:50:18,156 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Done
2011-11-29 11:50:50,000 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Customizing guest: ec2-75-101-201-86.compute-1.amazonaws.com
2011-11-29 11:50:50,000 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: Installing additional repository files
2011-11-29 11:50:53,085 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: Installing custom packages
2011-11-29 11:51:03,493 INFO oz.Guest.FedoraRemoteGuest pid(7467) Message: Uploading custom files
2011-11-29 11:51:03,494 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: Running custom commands
2011-11-29 11:51:03,494 DEBUG oz.Guest.FedoraRemoteGuest pid(7467) Message: Syncing
2011-11-29 11:51:04,300 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Customization step complete
2011-11-29 11:51:04,300 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Generating ICICLE from customized guest
2011-11-29 11:51:05,749 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: ICICLE generation complete
2011-11-29 11:51:05,749 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Re-de-activate firstboot just in case it has been revived during customize
2011-11-29 11:51:06,501 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: De-activation complete
2011-11-29 11:51:06,501 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Uploading cert material
2011-11-29 11:51:11,633 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Cert upload complete
2011-11-29 11:51:11,633 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Executing bundle vol command: euca-bundle-vol -c /tmp/tmpItkSnj -k /tmp/tmpdt8Qa0 -u 6735-0069-5950 -e /mnt,/tmp,/root/.ssh --arch x86_64 -d /mnt/bundles --kernel aki-427d952b -p 3100b50c-627a-4069-be3a-6dec1683180d -s 10240 --ec2cert /tmp/cert-ec2.pem --fstab /etc/fstab -v /
2011-11-29 11:55:13,347 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Bundle output: Filesystem label=/
OS type: Linux
Block size=4096 (log=2)
Fragment size=4096 (log=2)
Stride=0 blocks, Stripe width=0 blocks
655360 inodes, 2621440 blocks
131072 blocks (5.00%) reserved for the super user
First data block=0
Maximum filesystem blocks=2684354560
80 block groups
32768 blocks per group, 32768 fragments per group
8192 inodes per group
Superblock backups stored on blocks: 
	32768, 98304, 163840, 229376, 294912, 819200, 884736, 1605632

Writing inode tables: done                            
Creating journal (32768 blocks): done
Writing superblocks and filesystem accounting information: done

This filesystem will be automatically checked every 37 mounts or
180 days, whichever comes first.  Use tune2fs -c or -i to override.
tune2fs 1.41.12 (17-May-2010)
Unable to read ramdisk id
Unable to read product codes
Unable to read product codes
['blkid', '-sFS_TYPE', '-ovalue', '/tmp/tmpHHF0SI/dev']
['blkid', '-sUUID', '-ovalue', '/tmp/tmpHHF0SI/dev']
['blkid', '-sLABEL', '-ovalue', '/tmp/tmpHHF0SI/dev']
Warning: rsync reports files partially copied:
('', 'rsync: rsync_xal_clear: lremovexattr("etc/ld.so.conf.d/.libc6-xen.conf.ziTnzy","security.selinux") failed: Permission denied (13)\nrsync: rsync_xal_clear: lremovexattr("etc/sysconfig/.cloud-info.4EwV3u","security.selinux") failed: Permission denied (13)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1042) [sender=3.0.7]\n')
Checking image
Encrypting image
Splitting image...
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.00
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.01
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.02
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.03
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.04
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.05
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.06
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.07
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.08
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.09
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.10
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.11
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.12
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.13
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.14
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.15
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.16
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.17
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.18
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.19
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.20
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.21
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.22
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.23
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.24
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.25
Part: 3100b50c-627a-4069-be3a-6dec1683180d.part.26
Generating manifest /mnt/bundles/3100b50c-627a-4069-be3a-6dec1683180d.manifest.xml

2011-11-29 11:55:13,924 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Executing upload bundle command: ['euca-upload-bundle', '-b', 'imagefactory-ec2-us-east-1-6735-0069-5950', '-m', '/mnt/bundles/3100b50c-627a-4069-be3a-6dec1683180d.manifest.xml', '--ec2cert', '/tmp/cert-ec2.pem', '-a', 'REDACTED', '-s', 'REDACTED', '-U', 'http://s3.amazonaws.com/']
2011-11-29 11:55:27,705 DEBUG paste.httpserver.ThreadPool pid(7467) Message: Added task (0 tasks queued)
2011-11-29 11:55:33,504 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Upload output: Checking bucket: imagefactory-ec2-us-east-1-6735-0069-5950
Uploading manifest file
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.00
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.01
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.02
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.03
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.04
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.05
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.06
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.07
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.08
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.09
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.10
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.11
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.12
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.13
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.14
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.15
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.16
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.17
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.18
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.19
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.20
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.21
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.22
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.23
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.24
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.25
Uploading part: 3100b50c-627a-4069-be3a-6dec1683180d.part.26
Uploaded image as imagefactory-ec2-us-east-1-6735-0069-5950/3100b50c-627a-4069-be3a-6dec1683180d.manifest.xml

2011-11-29 11:55:33,504 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Executing register command: ['euca-register', '-U', 'http://ec2.us-east-1.amazonaws.com/', '-A', 'REDACTED', '-S', 'REDACTED', 'imagefactory-ec2-us-east-1-6735-0069-5950/3100b50c-627a-4069-be3a-6dec1683180d.manifest.xml']
2011-11-29 11:55:35,099 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Register output: IMAGE	ami-29b27940

2011-11-29 11:55:35,099 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Extracted AMI ID: ami-29b27940 
2011-11-29 11:55:35,102 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Image Warehouse returned status (500) with message: 
2011-11-29 11:55:35,103 INFO imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Creating a bucket returned status (500), .
2011-11-29 11:55:35,107 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Setting metadata ({'object_type': 'icicle', 'uuid': '8387c2f7-b48c-402a-b489-af95086e347a'}) for http://localhost:9090/icicles/8387c2f7-b48c-402a-b489-af95086e347a
2011-11-29 11:55:35,113 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Image Warehouse returned status (500) with message: 
2011-11-29 11:55:35,114 INFO imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Creating a bucket returned status (500), .
2011-11-29 11:55:35,133 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Setting metadata ({'target_image': '79fa5e17-e84e-4a21-9817-fd4f94bff552', 'uuid': '3100b50c-627a-4069-be3a-6dec1683180d', 'icicle': '8387c2f7-b48c-402a-b489-af95086e347a', 'target_identifier': 'ami-29b27940', 'object_type': 'provider_image', 'provider': 'ec2-us-east-1'}) for http://localhost:9090/provider_images/3100b50c-627a-4069-be3a-6dec1683180d
2011-11-29 11:55:35,146 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Terminating EC2 instance and deleting temp security group
2011-11-29 11:55:36,910 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Instance status (shutting-down) - waiting for 'terminated'. [0 of 300 seconds elapsed]
2011-11-29 11:55:42,011 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Instance status (shutting-down) - waiting for 'terminated'. [5 of 300 seconds elapsed]
2011-11-29 11:55:47,167 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Instance status (shutting-down) - waiting for 'terminated'. [10 of 300 seconds elapsed]
2011-11-29 11:55:53,975 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Removed temporary security group (imagefactory-3100b50c-627a-4069-be3a-6dec1683180d)
2011-11-29 11:55:53,975 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(7467) Message: Fedora_ec2_Builder instance 140673846803344 pushed image with uuid 79fa5e17-e84e-4a21-9817-fd4f94bff552 to provider_image UUID (3100b50c-627a-4069-be3a-6dec1683180d) and set metadata: {'target_image': '79fa5e17-e84e-4a21-9817-fd4f94bff552', 'target_identifier': 'ami-29b27940', 'icicle': '8387c2f7-b48c-402a-b489-af95086e347a', 'provider': 'ec2-us-east-1'}
2011-11-29 11:55:53,976 DEBUG imgfac.BuildJob.BuildJob pid(7467) Message: Builder (3100b50c-627a-4069-be3a-6dec1683180d) changed percent complete from 0 to 100
2011-11-29 11:55:53,976 DEBUG imgfac.BuildJob.BuildJob pid(7467) Message: Builder (3100b50c-627a-4069-be3a-6dec1683180d) changed status from PUSHING to COMPLETED
2011-11-29 11:55:53,976 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Setting metadata ({'latest_build': '5d7e49ec-4693-4430-a137-f92eac305887'}) for http://localhost:9090/images/9031ab80-36cd-4d04-b088-14eaa9c76ea0
2011-11-29 11:55:53,979 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(7467) Message: Setting metadata ({'latest_unpushed': None}) for http://localhost:9090/images/9031ab80-36cd-4d04-b088-14eaa9c76ea0

Comment 6 Jason Ganovsky 2011-11-29 21:40:45 UTC

Hung for me again, same spot.

2011-11-29 16:39:46,053 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(3214) Message: Customizing guest: ec2-50-19-78-143.compute-1.amazonaws.com
2011-11-29 16:39:46,054 DEBUG oz.Guest.FedoraRemoteGuest pid(3214) Message: Installing additional repository files

Comment 7 wes hayutin 2011-11-29 22:38:48 UTC

On a RHEL6.1 Server

[root@dhcp231-79 ~]# cat /etc/redhat-release 
Red Hat Enterprise Linux Server release 6.1 (Santiago)
[root@dhcp231-79 ~]# rpm -qa | grep aeolus
aeolus-configure-2.3.0-1.el6.noarch
aeolus-conductor-daemons-0.6.0-3.el6.noarch
rubygem-arel-2.0.10-0.aeolus.el6.noarch
rubygem-aeolus-cli-0.1.0-4.el6.noarch
aeolus-conductor-0.6.0-3.el6.noarch
aeolus-all-0.6.0-3.el6.noarch
rubygem-ZenTest-4.3.3-2.aeolus.el6.noarch
rubygem-aeolus-image-0.1.0-6.el6.noarch
aeolus-conductor-doc-0.6.0-3.el6.noarch
[root@dhcp231-79 ~]# rpm -qa | grep factory
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.el6.noarch
imagefactory-jeosconf-ec2-rhel-0.8.9-1.el6.noarch
imagefactory-0.8.9-1.el6.noarch
imagefactory-jeosconf-ec2-fedora-0.8.9-1.el6.noarch
[root@dhcp231-79 ~]# 


[root@dhcp231-79 ~]# aeolus-cli build --target ec2 --template RHEL755690.tpl 
Image: 88dbec35-21d4-40bc-9ae4-35d6c1216b6b
Build: b37bd712-ca5f-4165-9dbe-f7e740caea3f
Target Image: 89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11	 :Status New
[root@dhcp231-79 ~]# ./getTemplateStatus.py -i 89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11
COMPLETED
[root@dhcp231-79 ~]# aeolus-cli push --account ec2-us-east-1_AKIAJ557U7P7OIHRV2EQ --targetimage 89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11
Target Image: 89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11
Provider Image: b7887364-9bc2-4ba2-b747-b601e70610d1	 Status: New
[root@dhcp231-79 ~]# telnet 68.197.96.238 80
Trying 68.197.96.238...
Connected to 68.197.96.238.
Escape character is '^]'.


^C
Connection closed by foreign host.
[root@dhcp231-79 ~]# cat RHEL755690.tpl 
<template>
  <name>f14jeos</name>
  <os>
    <name>Fedora</name>
    <version>14</version>
    <arch>x86_64</arch>
    <install type='url'>
<url>http://download.fedoraproject.org/pub/fedora/linux/releases/14/Fedora/x86_64/os/</url>
    </install>
    <rootpw>passwd</rootpw>
  </os>
  <description>Fedora 14</description>
  <repositories>
    <repository name="custom">
      <url>http://lnxservices.dlinkddns.com/cflab/</url>
      <signed>false</signed>
    </repository>
  </repositories>
  <packages>
   <package name='httpd'/>
   <package name='web'/>
  </packages>
</template>
[root@dhcp231-79 ~]# 









011-11-29 17:26:56,158 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Uploading 0kB of 0kB
2011-11-29 17:26:56,158 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'icicle': 'none', 'uuid': '89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11', 'template': 'ce5a6baf-e05f-449a-9684-bb6a6cbfd942', 'target_parameters': None, 'object_type': 'target_image', 'target': 'ec2', 'build': 'b37bd712-ca5f-4165-9dbe-f7e740caea3f'}) for http://localhost:9090/target_images/89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11
2011-11-29 17:26:56,413 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11) changed percent complete from 0 to 100
2011-11-29 17:26:56,414 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11) changed status from NEW to COMPLETED
2011-11-29 17:26:56,414 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/88dbec35-21d4-40bc-9ae4-35d6c1216b6b
2011-11-29 17:26:56,416 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Image Warehouse returned status (404) with message: 
2011-11-29 17:26:56,416 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/88dbec35-21d4-40bc-9ae4-35d6c1216b6b
2011-11-29 17:26:56,418 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Image Warehouse returned status (404) with message: 
2011-11-29 17:26:56,419 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'latest_unpushed': 'b37bd712-ca5f-4165-9dbe-f7e740caea3f'}) for http://localhost:9090/images/88dbec35-21d4-40bc-9ae4-35d6c1216b6b
2011-11-29 17:26:56,422 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Completed placeholder warehouse object for linux non-upload image...
2011-11-29 17:27:18,592 DEBUG paste.httpserver.ThreadPool pid(23914) Message: Added task (0 tasks queued)
2011-11-29 17:27:58,583 DEBUG paste.httpserver.ThreadPool pid(23914) Message: Added task (0 tasks queued)
2011-11-29 17:27:58,626 INFO imgfac.rest.imagefactory pid(23914) Message: Request recieved with Content-Type (application/json)
2011-11-29 17:27:58,626 DEBUG imgfac.rest.imagefactory pid(23914) Message: returning {u'build_id': u'b37bd712-ca5f-4165-9dbe-f7e740caea3f', u'image_id': u'88dbec35-21d4-40bc-9ae4-35d6c1216b6b', u'provider': u'ec2-us-east-1', u'credentials': u'<provider_account>\n  <name>ec2-us-east-1_AKIAJ557U7P7OIHRV2EQ</name>\n  <provider>ec2-us-east-1</provider>\n  <provider_type>ec2</provider_type>\n  <provider_credentials>\n    <ec2_credentials>\n      <access_key>AKIAJ557U7P7OIHRV2EQ</access_key>\n      <account_number>6735-0069-5950</account_number>\n      <certificate>-----BEGIN CERTIFICATE-----\nMIICeDCCAeGgAwIBAgIGAIMhOJTUMA0GCSqGSIb3DQEBBQUAMFMxCzAJBgNVBAYT\nAlVTMRMwEQYDVQQKEwpBbWF6b24uY29tMQwwCgYDVQQLEwNBV1MxITAfBgNVBAMT\nGEFXUyBMaW1pdGVkLUFzc3VyYW5jZSBDQTAeFw0xMDA0MTkxNDU2MDRaFw0xMTA0\nMTkxNDU2MDRaMFMxCzAJBgNVBAYTAlVTMRMwEQYDVQQKEwpBbWF6b24uY29tMRcw\nFQYDVQQLEw5BV1MtRGV2ZWxvcGVyczEWMBQGA1UEAxMNMTNxb2pvMnhpa3AxMzCB\nnzANBgkqhkiG9w0BAQEFAAOBjQAwgYkCgYEAhsjKFoUJvEiZLdqALUAyFdDoeBKt\nZcqCRa5459+zp/d4XcykugpLlvcEfuKOrQcmqA08JWEN/4Xj4nIz+HoEtui3KFgg\n2YJ/VImlVeaSn4AGjfLsrEjLHnEBSGgNAJsh53fuEQTga8Y00T2qQ7d/Ebh6OXI3\niRKjY5Bm0d5mqrsCAwEAAaNXMFUwDgYDVR0PAQH/BAQDAgWgMBYGA1UdJQEB/wQM\nMAoGCCsGAQUFBwMCMAwGA1UdEwEB/wQCMAAwHQYDVR0OBBYEFDML4N2PsXn7nurg\n5bXHxMkFVQbLMA0GCSqGSIb3DQEBBQUAA4GBAIUGLc0R3HnEDXlXQMiLfQygqXEv\ndouihRgqCO+LOWb3tJbpk49TkhSFcjO0TJnKMb+XzuAdfswwgXSmwbbvrcDT/OdD\njHbCrLTCBBXIgShKZTrTYOAII5sokU4wg5QS0w9cEWcxceUWRaP8ELdai+ubrlif\noCeB2dgQxL18+uK5\n-----END CERTIFICATE-----\n</certificate>\n      <key>-----BEGIN PRIVATE KEY-----\nMIICdQIBADANBgkqhkiG9w0BAQEFAASCAl8wggJbAgEAAoGBAIbIyhaFCbxImS3agC1AMhXQ6HgS&#xD;\nrWXKgkWueOffs6f3eF3MpLoKS5b3BH7ijq0HJqgNPCVhDf+F4+JyM/h6BLbotyhYINmCf1SJpVXm&#xD;\nkp+ABo3y7KxIyx5xAUhoDQCbIed37hEE4GvGNNE9qkO3fxG4ejlyN4kSo2OQZtHeZqq7AgMBAAEC&#xD;\ngYByI9mXZoRPET++5/mB2IpRlT84Z8C1od/eOJkk63kNtfEVpfadoZhPuNHyzdM7TgH7cHHNybn7&#xD;\n+Jt6KXI/ar1QDIse7S+xU700AWJVaUt3FqlrglmGT018oGWqH1/3D9/4yVsdvxCKcKYuvre3j2gU&#xD;\nsi7uJPpWYqCLhs41EuIUiQJBAPcuRXEtVV5MJipuYljERZHfxT4Yh/b8agfr1dsrHvd4jly9rJnE&#xD;\nIwB3vn0mMS9Fs/f0rUc3RAZRSrsuwYravU8CQQCLl+YDqZOwVfZIkJO2ipWb1xgjVuikLDRTYAXr&#xD;\n0NqbDdeBozAdPNVvicrGpg61J0Un6r5zjnkMFfS6SpM4bFjVAkAuwbz3DLlCTitGCX1nBzGVx3t7&#xD;\nJI7LpLG17+GTRNPlwhND7ckxiQ2J8BzDda8GHyxM8ia4+vZOL6BwSgboajo3AkAsiTjkYDzzXGmn&#xD;\n+yB1c7+JqdVUqQyuHEBRfWhGNSsZnw/rl1HpchgybsbYG/DDURUaSCWdHo52EaczrElODhtFAkAU&#xD;\n95df+umPXSwSzFaSCVVaGm7QlH8yBx8nFHF+yeFPV5QmDWmj59Y9UM6vM4MHtUAdpEhIzzAoI/Et&#xD;\nlMA4EBbB&#xD;\n-----END PRIVATE KEY-----\n</key>\n      <secret_access_key>7COhvBzxrz2agtOPgk3bZM7W3ej9QI/j6WvYiHdg</secret_access_key>\n    </ec2_credentials>\n  </provider_credentials>\n</provider_account>', u'target_image_id': u'89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11'}
2011-11-29 17:27:58,627 DEBUG imgfac.rest.imagefactory pid(23914) Message: Starting 'push' process...
2011-11-29 17:27:58,627 INFO imgfac.rest.imagefactory pid(23914) Message: Request recieved with Content-Type (application/json)
2011-11-29 17:27:58,628 DEBUG imgfac.rest.imagefactory pid(23914) Message: returning {u'build_id': u'b37bd712-ca5f-4165-9dbe-f7e740caea3f', u'image_id': u'88dbec35-21d4-40bc-9ae4-35d6c1216b6b', u'provider': u'ec2-us-east-1', u'credentials': u'<provider_account>\n  <name>ec2-us-east-1_AKIAJ557U7P7OIHRV2EQ</name>\n  <provider>ec2-us-east-1</provider>\n  <provider_type>ec2</provider_type>\n  <provider_credentials>\n    <ec2_credentials>\n      <access_key>AKIAJ557U7P7OIHRV2EQ</access_key>\n      <account_number>6735-0069-5950</account_number>\n      <certificate>-----BEGIN CERTIFICATE-----\nMIICeDCCAeGgAwIBAgIGAIMhOJTUMA0GCSqGSIb3DQEBBQUAMFMxCzAJBgNVBAYT\nAlVTMRMwEQYDVQQKEwpBbWF6b24uY29tMQwwCgYDVQQLEwNBV1MxITAfBgNVBAMT\nGEFXUyBMaW1pdGVkLUFzc3VyYW5jZSBDQTAeFw0xMDA0MTkxNDU2MDRaFw0xMTA0\nMTkxNDU2MDRaMFMxCzAJBgNVBAYTAlVTMRMwEQYDVQQKEwpBbWF6b24uY29tMRcw\nFQYDVQQLEw5BV1MtRGV2ZWxvcGVyczEWMBQGA1UEAxMNMTNxb2pvMnhpa3AxMzCB\nnzANBgkqhkiG9w0BAQEFAAOBjQAwgYkCgYEAhsjKFoUJvEiZLdqALUAyFdDoeBKt\nZcqCRa5459+zp/d4XcykugpLlvcEfuKOrQcmqA08JWEN/4Xj4nIz+HoEtui3KFgg\n2YJ/VImlVeaSn4AGjfLsrEjLHnEBSGgNAJsh53fuEQTga8Y00T2qQ7d/Ebh6OXI3\niRKjY5Bm0d5mqrsCAwEAAaNXMFUwDgYDVR0PAQH/BAQDAgWgMBYGA1UdJQEB/wQM\nMAoGCCsGAQUFBwMCMAwGA1UdEwEB/wQCMAAwHQYDVR0OBBYEFDML4N2PsXn7nurg\n5bXHxMkFVQbLMA0GCSqGSIb3DQEBBQUAA4GBAIUGLc0R3HnEDXlXQMiLfQygqXEv\ndouihRgqCO+LOWb3tJbpk49TkhSFcjO0TJnKMb+XzuAdfswwgXSmwbbvrcDT/OdD\njHbCrLTCBBXIgShKZTrTYOAII5sokU4wg5QS0w9cEWcxceUWRaP8ELdai+ubrlif\noCeB2dgQxL18+uK5\n-----END CERTIFICATE-----\n</certificate>\n      <key>-----BEGIN PRIVATE KEY-----\nMIICdQIBADANBgkqhkiG9w0BAQEFAASCAl8wggJbAgEAAoGBAIbIyhaFCbxImS3agC1AMhXQ6HgS&#xD;\nrWXKgkWueOffs6f3eF3MpLoKS5b3BH7ijq0HJqgNPCVhDf+F4+JyM/h6BLbotyhYINmCf1SJpVXm&#xD;\nkp+ABo3y7KxIyx5xAUhoDQCbIed37hEE4GvGNNE9qkO3fxG4ejlyN4kSo2OQZtHeZqq7AgMBAAEC&#xD;\ngYByI9mXZoRPET++5/mB2IpRlT84Z8C1od/eOJkk63kNtfEVpfadoZhPuNHyzdM7TgH7cHHNybn7&#xD;\n+Jt6KXI/ar1QDIse7S+xU700AWJVaUt3FqlrglmGT018oGWqH1/3D9/4yVsdvxCKcKYuvre3j2gU&#xD;\nsi7uJPpWYqCLhs41EuIUiQJBAPcuRXEtVV5MJipuYljERZHfxT4Yh/b8agfr1dsrHvd4jly9rJnE&#xD;\nIwB3vn0mMS9Fs/f0rUc3RAZRSrsuwYravU8CQQCLl+YDqZOwVfZIkJO2ipWb1xgjVuikLDRTYAXr&#xD;\n0NqbDdeBozAdPNVvicrGpg61J0Un6r5zjnkMFfS6SpM4bFjVAkAuwbz3DLlCTitGCX1nBzGVx3t7&#xD;\nJI7LpLG17+GTRNPlwhND7ckxiQ2J8BzDda8GHyxM8ia4+vZOL6BwSgboajo3AkAsiTjkYDzzXGmn&#xD;\n+yB1c7+JqdVUqQyuHEBRfWhGNSsZnw/rl1HpchgybsbYG/DDURUaSCWdHo52EaczrElODhtFAkAU&#xD;\n95df+umPXSwSzFaSCVVaGm7QlH8yBx8nFHF+yeFPV5QmDWmj59Y9UM6vM4MHtUAdpEhIzzAoI/Et&#xD;\nlMA4EBbB&#xD;\n-----END PRIVATE KEY-----\n</key>\n      <secret_access_key>7COhvBzxrz2agtOPgk3bZM7W3ej9QI/j6WvYiHdg</secret_access_key>\n    </ec2_credentials>\n  </provider_credentials>\n</provider_account>', u'target_image_id': u'89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11'}
2011-11-29 17:27:58,629 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Querying (http://localhost:9090/target_images/_query) with expression ($build == "b37bd712-ca5f-4165-9dbe-f7e740caea3f" && $target == "ec2")
2011-11-29 17:27:58,671 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Getting metadata (['template']) from http://localhost:9090/target_images/89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11
2011-11-29 17:27:58,673 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-11-29 17:27:58,677 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-11-29 17:27:58,678 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Found neither a call-time config nor a config file - doing nothing
2011-11-29 17:27:58,692 DEBUG paste.httpserver.ThreadPool pid(23914) Message: Added task (0 tasks queued)
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Original URL http://download.fedoraproject.org/pub/fedora/linux/releases/14/Fedora/x86_64/os/ resolved to http://download.fedora.devel.redhat.com/pub/fedora/linux/releases/14/Fedora/x86_64/os/
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Name: f14jeos-b7887364-9bc2-4ba2-b747-b601e70610d1, UUID: f84c366a-66b2-4555-9bb7-8b6a4262d1e7
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: MAC: 52:54:00:5d:40:f6, distro: Fedora
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: update: 14, arch: x86_64, diskimage: /home/var/lib/imagefactory/images/f14jeos-b7887364-9bc2-4ba2-b747-b601e70610d1.dsk
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: nicmodel: virtio, clockoffset: utc
2011-11-29 17:27:59,089 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: mousetype: ps2, disk_bus: virtio, disk_dev: vda
2011-11-29 17:27:59,090 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: icicletmp: /home/var/lib/oz/icicletmp/f14jeos-b7887364-9bc2-4ba2-b747-b601e70610d1, listen_port: 15324
2011-11-29 17:27:59,090 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Original ISO path: /home/var/lib/oz/isos/Fedora14x86_64-url.iso
2011-11-29 17:27:59,090 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Modified ISO cache: /home/var/lib/oz/isos/Fedora14x86_64-url-oz.iso
2011-11-29 17:27:59,090 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Output ISO path: /home/var/lib/imagefactory/images/f14jeos-b7887364-9bc2-4ba2-b747-b601e70610d1-url-oz.iso
2011-11-29 17:27:59,090 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: ISO content path: /home/var/lib/oz/isocontent/f14jeos-b7887364-9bc2-4ba2-b747-b601e70610d1-url
2011-11-29 17:27:59,090 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Being asked to push for provider ec2-us-east-1
2011-11-29 17:27:59,091 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: distro: Fedora - update: 14 - arch: x86_64
2011-11-29 17:27:59,092 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: acting as EC2 user: 6735-0069-5950
2011-11-29 17:27:59,092 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (b7887364-9bc2-4ba2-b747-b601e70610d1) changed status from NEW to PUSHING
2011-11-29 17:27:59,092 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (b7887364-9bc2-4ba2-b747-b601e70610d1) changed percent complete from 0 to 0
2011-11-29 17:27:59,092 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Starting ami ami-5b1dd932 with instance_type m1.large
2011-11-29 17:27:59,313 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Creating temporary security group (imagefactory-b7887364-9bc2-4ba2-b747-b601e70610d1)
2011-11-29 17:28:02,440 WARNING boto pid(23914) Message: Error converting code (pending) to int
2011-11-29 17:28:12,451 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting for EC2 instance to start: 0/300
2011-11-29 17:28:23,222 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting for EC2 instance to start: 10/300
2011-11-29 17:28:33,920 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting for EC2 instance to start: 20/300
2011-11-29 17:28:44,569 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting for EC2 instance to start: 30/300
2011-11-29 17:28:48,916 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting for EC2 ssh access: 0/300
2011-11-29 17:29:13,323 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Waiting 20 seconds for remaining boot tasks
2011-11-29 17:29:33,344 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Stopping cron and killing any updatedb process that may be running
2011-11-29 17:29:34,811 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Done
2011-11-29 17:29:56,549 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Customizing guest: ec2-107-22-78-89.compute-1.amazonaws.com
2011-11-29 17:29:56,550 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Installing additional repository files
2011-11-29 17:29:58,452 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Installing custom packages
2011-11-29 17:30:09,565 INFO oz.Guest.FedoraRemoteGuest pid(23914) Message: Uploading custom files
2011-11-29 17:30:09,566 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Running custom commands
2011-11-29 17:30:09,566 DEBUG oz.Guest.FedoraRemoteGuest pid(23914) Message: Syncing
2011-11-29 17:30:10,122 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Customization step complete
2011-11-29 17:30:10,123 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Generating ICICLE from customized guest
2011-11-29 17:30:11,141 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: ICICLE generation complete
2011-11-29 17:30:11,141 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Re-de-activate firstboot just in case it has been revived during customize
2011-11-29 17:30:11,622 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: De-activation complete
2011-11-29 17:30:11,622 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Uploading cert material
2011-11-29 17:30:14,732 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Cert upload complete
2011-11-29 17:30:14,732 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Executing bundle vol command: euca-bundle-vol -c /tmp/tmpNnIxXm -k /tmp/tmpOeScFn -u 6735-0069-5950 -e /mnt,/tmp,/root/.ssh --arch x86_64 -d /mnt/bundles --kernel aki-427d952b -p b7887364-9bc2-4ba2-b747-b601e70610d1 -s 10240 --ec2cert /tmp/cert-ec2.pem --fstab /etc/fstab -v /
2011-11-29 17:34:20,693 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Bundle output: Filesystem label=/
OS type: Linux
Block size=4096 (log=2)
Fragment size=4096 (log=2)
Stride=0 blocks, Stripe width=0 blocks
655360 inodes, 2621440 blocks
131072 blocks (5.00%) reserved for the super user
First data block=0
Maximum filesystem blocks=2684354560
80 block groups
32768 blocks per group, 32768 fragments per group
8192 inodes per group
Superblock backups stored on blocks: 
	32768, 98304, 163840, 229376, 294912, 819200, 884736, 1605632

Writing inode tables: done                            
Creating journal (32768 blocks): done
Writing superblocks and filesystem accounting information: done

This filesystem will be automatically checked every 28 mounts or
180 days, whichever comes first.  Use tune2fs -c or -i to override.
tune2fs 1.41.12 (17-May-2010)
Unable to read ramdisk id
Unable to read product codes
Unable to read product codes
['blkid', '-sFS_TYPE', '-ovalue', '/tmp/tmprrIy4X/dev']
['blkid', '-sUUID', '-ovalue', '/tmp/tmprrIy4X/dev']
['blkid', '-sLABEL', '-ovalue', '/tmp/tmprrIy4X/dev']
Warning: rsync reports files partially copied:
('', 'rsync: rsync_xal_clear: lremovexattr("etc/ld.so.conf.d/.libc6-xen.conf.t2rqMv","security.selinux") failed: Permission denied (13)\nrsync: rsync_xal_clear: lremovexattr("etc/sysconfig/.cloud-info.B8uJsn","security.selinux") failed: Permission denied (13)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1042) [sender=3.0.7]\n')
Checking image
Encrypting image
Splitting image...
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.00
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.01
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.02
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.03
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.04
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.05
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.06
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.07
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.08
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.09
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.10
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.11
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.12
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.13
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.14
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.15
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.16
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.17
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.18
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.19
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.20
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.21
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.22
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.23
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.24
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.25
Part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.26
Generating manifest /mnt/bundles/b7887364-9bc2-4ba2-b747-b601e70610d1.manifest.xml

2011-11-29 17:34:20,982 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Executing upload bundle command: ['euca-upload-bundle', '-b', 'imagefactory-ec2-us-east-1-6735-0069-5950', '-m', '/mnt/bundles/b7887364-9bc2-4ba2-b747-b601e70610d1.manifest.xml', '--ec2cert', '/tmp/cert-ec2.pem', '-a', 'REDACTED', '-s', 'REDACTED', '-U', 'http://s3.amazonaws.com/']
2011-11-29 17:34:42,538 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Upload output: Checking bucket: imagefactory-ec2-us-east-1-6735-0069-5950
Uploading manifest file
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.00
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.01
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.02
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.03
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.04
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.05
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.06
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.07
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.08
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.09
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.10
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.11
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.12
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.13
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.14
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.15
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.16
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.17
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.18
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.19
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.20
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.21
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.22
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.23
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.24
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.25
Uploading part: b7887364-9bc2-4ba2-b747-b601e70610d1.part.26
Uploaded image as imagefactory-ec2-us-east-1-6735-0069-5950/b7887364-9bc2-4ba2-b747-b601e70610d1.manifest.xml

2011-11-29 17:34:42,538 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Executing register command: ['euca-register', '-U', 'http://ec2.us-east-1.amazonaws.com/', '-A', 'REDACTED', '-S', 'REDACTED', 'imagefactory-ec2-us-east-1-6735-0069-5950/b7887364-9bc2-4ba2-b747-b601e70610d1.manifest.xml']
2011-11-29 17:34:45,238 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Register output: IMAGE	ami-1bcb0072

2011-11-29 17:34:45,238 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Extracted AMI ID: ami-1bcb0072 
2011-11-29 17:34:45,241 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Image Warehouse returned status (500) with message: 
2011-11-29 17:34:45,241 INFO imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Creating a bucket returned status (500), .
2011-11-29 17:34:45,246 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'object_type': 'icicle', 'uuid': '40eeb50b-979a-4bdd-9dec-4e97ff5506d3'}) for http://localhost:9090/icicles/40eeb50b-979a-4bdd-9dec-4e97ff5506d3
2011-11-29 17:34:45,332 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Image Warehouse returned status (500) with message: 
2011-11-29 17:34:45,332 INFO imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Creating a bucket returned status (500), .
2011-11-29 17:34:45,336 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'target_image': '89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11', 'uuid': 'b7887364-9bc2-4ba2-b747-b601e70610d1', 'icicle': '40eeb50b-979a-4bdd-9dec-4e97ff5506d3', 'target_identifier': 'ami-1bcb0072', 'object_type': 'provider_image', 'provider': 'ec2-us-east-1'}) for http://localhost:9090/provider_images/b7887364-9bc2-4ba2-b747-b601e70610d1
2011-11-29 17:34:45,589 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Terminating EC2 instance and deleting temp security group
2011-11-29 17:34:47,031 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:34:47,031 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Instance status (shutting-down) - waiting for 'terminated'. [0 of 300 seconds elapsed]
2011-11-29 17:34:52,117 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:34:52,117 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Instance status (shutting-down) - waiting for 'terminated'. [5 of 300 seconds elapsed]
2011-11-29 17:34:57,184 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:34:57,185 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Instance status (shutting-down) - waiting for 'terminated'. [10 of 300 seconds elapsed]
2011-11-29 17:35:02,317 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:35:02,317 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Instance status (shutting-down) - waiting for 'terminated'. [15 of 300 seconds elapsed]
2011-11-29 17:35:07,380 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:35:07,381 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Instance status (shutting-down) - waiting for 'terminated'. [20 of 300 seconds elapsed]
2011-11-29 17:35:12,465 WARNING boto pid(23914) Message: Error converting code (Client.UserInitiatedShutdown) to int
2011-11-29 17:35:12,748 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Removed temporary security group (imagefactory-b7887364-9bc2-4ba2-b747-b601e70610d1)
2011-11-29 17:35:12,748 DEBUG imgfac.builders.BaseBuilder.Fedora_ec2_Builder pid(23914) Message: Fedora_ec2_Builder instance 140609311973520 pushed image with uuid 89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11 to provider_image UUID (b7887364-9bc2-4ba2-b747-b601e70610d1) and set metadata: {'target_image': '89fa7e73-dbf7-4bdb-96f1-cf9f7c569d11', 'target_identifier': 'ami-1bcb0072', 'icicle': '40eeb50b-979a-4bdd-9dec-4e97ff5506d3', 'provider': 'ec2-us-east-1'}
2011-11-29 17:35:12,749 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (b7887364-9bc2-4ba2-b747-b601e70610d1) changed percent complete from 0 to 100
2011-11-29 17:35:12,749 DEBUG imgfac.BuildJob.BuildJob pid(23914) Message: Builder (b7887364-9bc2-4ba2-b747-b601e70610d1) changed status from PUSHING to COMPLETED
2011-11-29 17:35:12,749 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'latest_build': 'b37bd712-ca5f-4165-9dbe-f7e740caea3f'}) for http://localhost:9090/images/88dbec35-21d4-40bc-9ae4-35d6c1216b6b
2011-11-29 17:35:12,753 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(23914) Message: Setting metadata ({'latest_unpushed': None}) for http://localhost:9090/images/88dbec35-21d4-40bc-9ae4-35d6c1216b6b
q^C

Comment 8 wes hayutin 2011-11-29 22:39:45 UTC

Please do not reopen this bug.. it has been verified on production/latest releases of aeolus on f15 and RHEL 6.1

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