Attachment 574800 Details for Bug 795403 – ImageFactoryLogs_Pushpesh

ImageFactoryLogs_Pushpesh

imagefactory.log (text/x-log), 11.76 KB, created by pushpesh sharma on 2012-04-03 09:43:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: pushpesh sharma

Created: 2012-04-03 09:43:06 UTC

Size: 11.76 KB

patch

obsolete

>[root@qe-blade-01 ~]# tail -f /var/log/imagefactory.log
>2012-04-03 05:05:19,397 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: b7c93141-5a94-4247-a887-9f7ff36edfd0 for ec2 about to enter ec2 queue...
>2012-04-03 05:05:19,397 DEBUG imgfac.ReservationManager.ReservationManager thread(b7c93141) Message: ENTERING queue: (ec2)
>2012-04-03 05:05:19,397 DEBUG imgfac.ReservationManager.ReservationManager thread(b7c93141) Message: SUCCESS ENTERING queue: (ec2)
>2012-04-03 05:05:19,398 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: Builder (b7c93141-5a94-4247-a887-9f7ff36edfd0) changed status from NEW to PUSHING
>2012-04-03 05:05:19,398 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: Builder (b7c93141-5a94-4247-a887-9f7ff36edfd0) changed percent complete from 0 to 0
>2012-04-03 05:05:21,456 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Creating temporary security group (imagefactory-b7c93141-5a94-4247-a887-9f7ff36edfd0)
>2012-04-03 05:05:24,150 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Starting ami ami-94f58ec6 with instance_type m1.large
>2012-04-03 05:05:25,317 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Waiting for EC2 instance to start: 0/300
>2012-04-03 05:05:41,573 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Waiting for EC2 instance to start: 10/300
>2012-04-03 05:05:47,066 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Waiting for EC2 ssh access: 0/300
>2012-04-03 05:06:23,731 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Waiting for EC2 ssh access: 10/300
>2012-04-03 05:06:31,189 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Waiting 20 seconds for remaining boot tasks
>2012-04-03 05:06:51,210 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Stopping cron and killing any updatedb process that may be running
>2012-04-03 05:06:58,710 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Done
>2012-04-03 05:06:58,710 INFO imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Creating cloud-info file indicating target (ec2)
>2012-04-03 05:07:02,246 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Customizing guest: ec2-122-248-230-155.ap-southeast-1.compute.amazonaws.com
>2012-04-03 05:07:02,247 DEBUG oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Installing additional repository files
>2012-04-03 05:07:02,247 DEBUG oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Installing custom packages
>2012-04-03 05:07:02,247 INFO oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Uploading custom files
>2012-04-03 05:07:02,247 DEBUG oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Running custom commands
>2012-04-03 05:07:02,247 DEBUG oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Removing non-persisted repos
>2012-04-03 05:07:02,248 DEBUG oz.Guest.RHEL6RemoteGuest thread(b7c93141) Message: Syncing
>2012-04-03 05:07:05,919 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Customization step complete
>2012-04-03 05:07:05,919 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Generating ICICLE from customized guest
>2012-04-03 05:07:12,024 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: ICICLE generation complete
>2012-04-03 05:07:12,024 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Re-de-activate firstboot just in case it has been revived during customize
>2012-04-03 05:07:15,670 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: De-activation complete
>2012-04-03 05:07:15,670 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Performing image prep tasks for EBS backed images
>2012-04-03 05:07:15,670 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Removing existing authorized_keys file to allow key injection on RHEL reboot
>2012-04-03 05:07:19,245 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Creating a new EBS backed image from our running EBS instance
>2012-04-03 05:07:19,902 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: EUCA creat_image call returned AMI ID: ami-70612622
>2012-04-03 05:07:19,903 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Now waiting for AMI to become available
>2012-04-03 05:07:31,240 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: AMI status (pending) - waiting for 'available' - [0 of 1200 seconds elapsed]
>2012-04-03 05:07:41,863 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: AMI status (pending) - waiting for 'available' - [10 of 1200 seconds elapsed]
>2012-04-03 05:07:52,473 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: AMI status (pending) - waiting for 'available' - [20 of 1200 seconds elapsed]
>2012-04-03 05:08:03,554 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: AMI status (pending) - waiting for 'available' - [30 of 1200 seconds elapsed]
>2012-04-03 05:08:14,165 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: AMI status (pending) - waiting for 'available' - [40 of 1200 seconds elapsed]
>2012-04-03 05:08:24,918 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Image Warehouse returned status (500) with message: 
>2012-04-03 05:08:24,918 INFO imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Creating a bucket returned status (500), .
>2012-04-03 05:08:24,921 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Setting metadata ({'object_type': 'icicle', 'uuid': '2af43144-032f-42a3-b58e-650306b97766'}) for http://localhost:9090/icicles/2af43144-032f-42a3-b58e-650306b97766
>2012-04-03 05:08:25,004 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Image Warehouse returned status (500) with message: 
>2012-04-03 05:08:25,004 INFO imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Creating a bucket returned status (500), .
>2012-04-03 05:08:25,006 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Setting metadata ({'provider_account_identifier': 'AKIAIXL2ECBLMAI5GEKQ', 'target_image': '096c1143-897d-4544-ac66-5c0a0e7ece82', 'uuid': 'b7c93141-5a94-4247-a887-9f7ff36edfd0', 'icicle': '2af43144-032f-42a3-b58e-650306b97766', 'target_identifier': u'ami-70612622', 'object_type': 'provider_image', 'provider': 'ec2-ap-southeast-1'}) for http://localhost:9090/provider_images/b7c93141-5a94-4247-a887-9f7ff36edfd0
>2012-04-03 05:08:25,293 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Terminating EC2 instance and deleting temp security group
>2012-04-03 05:08:27,850 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [0 of 300 seconds elapsed]
>2012-04-03 05:08:33,466 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [5 of 300 seconds elapsed]
>2012-04-03 05:08:39,129 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [10 of 300 seconds elapsed]
>2012-04-03 05:08:44,752 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [15 of 300 seconds elapsed]
>2012-04-03 05:08:50,368 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [20 of 300 seconds elapsed]
>2012-04-03 05:08:55,984 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [25 of 300 seconds elapsed]
>2012-04-03 05:09:01,614 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [30 of 300 seconds elapsed]
>2012-04-03 05:09:07,239 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [35 of 300 seconds elapsed]
>2012-04-03 05:09:12,853 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [40 of 300 seconds elapsed]
>2012-04-03 05:09:18,469 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [45 of 300 seconds elapsed]
>2012-04-03 05:09:24,091 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [50 of 300 seconds elapsed]
>2012-04-03 05:09:29,712 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [55 of 300 seconds elapsed]
>2012-04-03 05:09:35,330 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [60 of 300 seconds elapsed]
>2012-04-03 05:09:40,993 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [65 of 300 seconds elapsed]
>2012-04-03 05:09:46,621 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Instance status (shutting-down) - waiting for 'terminated'. [70 of 300 seconds elapsed]
>2012-04-03 05:09:52,953 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Removed temporary security group (imagefactory-b7c93141-5a94-4247-a887-9f7ff36edfd0)
>2012-04-03 05:09:52,953 DEBUG imgfac.builders.BaseBuilder.RHEL6_ec2_Builder thread(b7c93141) Message: Fedora_ec2_Builder instance 139984394464400 pushed image with uuid 096c1143-897d-4544-ac66-5c0a0e7ece82 to provider_image UUID (b7c93141-5a94-4247-a887-9f7ff36edfd0) and set metadata: {'provider_account_identifier': 'AKIAIXL2ECBLMAI5GEKQ', 'target_image': '096c1143-897d-4544-ac66-5c0a0e7ece82', 'target_identifier': u'ami-70612622', 'icicle': '2af43144-032f-42a3-b58e-650306b97766', 'provider': 'ec2-ap-southeast-1'}
>2012-04-03 05:09:52,953 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: Builder (b7c93141-5a94-4247-a887-9f7ff36edfd0) changed percent complete from 0 to 100
>2012-04-03 05:09:52,954 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: Builder (b7c93141-5a94-4247-a887-9f7ff36edfd0) changed status from PUSHING to COMPLETED
>2012-04-03 05:09:52,954 DEBUG imgfac.BuildJob.BuildJob thread(b7c93141) Message: b7c93141-5a94-4247-a887-9f7ff36edfd0 for ec2 about to exit ec2 queue...
>2012-04-03 05:09:52,954 DEBUG imgfac.ReservationManager.ReservationManager thread(b7c93141) Message: EXITING queue: (ec2)
>2012-04-03 05:09:52,954 DEBUG imgfac.ReservationManager.ReservationManager thread(b7c93141) Message: SUCCESS EXITING queue: (ec2)
>2012-04-03 05:09:52,954 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Setting metadata ({'latest_build': '73dccd80-de19-4745-8eed-4fa848ddc078'}) for http://localhost:9090/images/10e4c398-7d6c-11e1-9a08-00215e20316a
>2012-04-03 05:09:52,957 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Image Warehouse returned status (404) with message: 404 no such object
>
>2012-04-03 05:09:52,957 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Setting metadata ({'latest_unpushed': None}) for http://localhost:9090/images/10e4c398-7d6c-11e1-9a08-00215e20316a
>2012-04-03 05:09:52,959 DEBUG imgfac.ImageWarehouse.ImageWarehouse thread(b7c93141) Message: Image Warehouse returned status (404) with message: 404 no such object
>

Actions: View

Attachments on bug 795403: 564424 | 574800