/tmp/CloudF29.39Wbwy ~
Cloning into 'pungi-fedora'...
Switched to a new branch 'f29'
Branch 'f29' set up to track remote branch 'f29' from 'origin'.
Cloning into 'releng'...
WARNING: Config option gather_source was removed and has no effect; remove it.
2019-02-03 07:15:08 [INFO ] Automatically setting 'supported' flag due to
label: RC-20190203.0.
2019-02-03 07:15:08 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'status-change', {'status': 'STARTED', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:09 [INFO ] Host:
compose-x86-01.phx2.fedoraproject.org
2019-02-03 07:15:09 [INFO ] Pungi version: b'pungi-4.1.30-1.fc29.noarch'
2019-02-03 07:15:09 [INFO ] User name: root
2019-02-03 07:15:09 [INFO ] Working directory: /tmp/CloudF29.39Wbwy/pungi-fedora
2019-02-03 07:15:09 [INFO ] Command line: /usr/bin/pungi-koji
--notification-script=/usr/bin/pungi-fedmsg-notification --config=fedora-cloud.conf
--old-composes=/mnt/koji/compose/cloud --label=RC-20190203.0
--target-dir=/mnt/koji/compose/cloud
2019-02-03 07:15:09 [INFO ] Compose top directory:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0
2019-02-03 07:15:09 [INFO ] Current timezone offset: +00:00
2019-02-03 07:15:09 [DEBUG ] Writing variants file:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/global/variants.xml
2019-02-03 07:15:09 [DEBUG ] Exporting file
/tmp/CloudF29.39Wbwy/pungi-fedora/variants-fedora.xml from current working directory...
2019-02-03 07:15:09 [INFO ] Excluding variant AtomicHost: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Container: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Everything: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Labs: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Server: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Spins: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Workstation: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Silverblue: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Excluding variant Modular: filtered by configuration.
2019-02-03 07:15:09 [INFO ] Config section 'live_images' was not found.
Skipping.
2019-02-03 07:15:09 [INFO ] Config section 'live_media' was not found.
Skipping.
2019-02-03 07:15:09 [INFO ] Config section 'ostree' was not found. Skipping.
2019-02-03 07:15:09 [INFO ] Config section 'ostree_installer' was not found.
Skipping.
2019-02-03 07:15:09 [INFO ] Config section 'extra_isos' was not found.
Skipping.
2019-02-03 07:15:09 [INFO ] Config section 'osbs' was not found. Skipping.
2019-02-03 07:15:09 [INFO ] [BEGIN] ---------- PHASE: INIT ----------
2019-02-03 07:15:09 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'init', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:11 [DEBUG ] Writing global comps file:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/global/comps/comps-global.xml
2019-02-03 07:15:11 [DEBUG ] Exporting file comps-f29.xml from git
https://pagure.io/fedora-comps.git (branch master)...
2019-02-03 07:15:13 [DEBUG ] Running "make comps-f29.xml"
2019-02-03 07:15:17 [DEBUG ] Writing comps file for arch 'aarch64':
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/aarch64/comps/comps-aarch64.xml
2019-02-03 07:15:17 [INFO ] [BEGIN] Creating comps repo for arch 'aarch64'
variant 'None'
2019-02-03 07:15:17 [INFO ] [DONE ] Creating comps repo for arch 'aarch64'
variant 'None'
2019-02-03 07:15:17 [DEBUG ] Writing comps file for arch 'ppc64le':
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/ppc64le/comps/comps-ppc64le.xml
2019-02-03 07:15:17 [INFO ] [BEGIN] Creating comps repo for arch 'ppc64le'
variant 'None'
2019-02-03 07:15:18 [INFO ] [DONE ] Creating comps repo for arch 'ppc64le'
variant 'None'
2019-02-03 07:15:18 [DEBUG ] Writing comps file for arch 's390x':
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/s390x/comps/comps-s390x.xml
2019-02-03 07:15:18 [INFO ] [BEGIN] Creating comps repo for arch 's390x'
variant 'None'
2019-02-03 07:15:18 [INFO ] [DONE ] Creating comps repo for arch 's390x'
variant 'None'
2019-02-03 07:15:18 [DEBUG ] Writing comps file for arch 'x86_64':
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/x86_64/comps/comps-x86_64.xml
2019-02-03 07:15:18 [INFO ] [BEGIN] Creating comps repo for arch 'x86_64'
variant 'None'
2019-02-03 07:15:19 [INFO ] [DONE ] Creating comps repo for arch 'x86_64'
variant 'None'
2019-02-03 07:15:19 [DEBUG ] Writing comps file (arch: aarch64, variant: Cloud):
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/aarch64/comps/comps-Cloud.aarch64.xml
2019-02-03 07:15:20 [INFO ] [BEGIN] Creating comps repo for arch 'aarch64'
variant 'Cloud'
2019-02-03 07:15:20 [INFO ] [DONE ] Creating comps repo for arch 'aarch64'
variant 'Cloud'
2019-02-03 07:15:20 [DEBUG ] Writing comps file (arch: ppc64le, variant: Cloud):
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/ppc64le/comps/comps-Cloud.ppc64le.xml
2019-02-03 07:15:21 [INFO ] [BEGIN] Creating comps repo for arch 'ppc64le'
variant 'Cloud'
2019-02-03 07:15:21 [INFO ] [DONE ] Creating comps repo for arch 'ppc64le'
variant 'Cloud'
2019-02-03 07:15:21 [DEBUG ] Writing comps file (arch: s390x, variant: Cloud):
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/s390x/comps/comps-Cloud.s390x.xml
2019-02-03 07:15:22 [INFO ] [BEGIN] Creating comps repo for arch 's390x'
variant 'Cloud'
2019-02-03 07:15:23 [INFO ] [DONE ] Creating comps repo for arch 's390x'
variant 'Cloud'
2019-02-03 07:15:23 [DEBUG ] Writing comps file (arch: x86_64, variant: Cloud):
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/x86_64/comps/comps-Cloud.x86_64.xml
2019-02-03 07:15:24 [INFO ] [BEGIN] Creating comps repo for arch 'x86_64'
variant 'Cloud'
2019-02-03 07:15:24 [INFO ] [DONE ] Creating comps repo for arch 'x86_64'
variant 'Cloud'
2019-02-03 07:15:24 [INFO ] [DONE ] ---------- PHASE: INIT ----------
2019-02-03 07:15:24 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-stop', {'phase_name': 'init', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: PKGSET ----------
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: BUILDINSTALL ----------
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: GATHER ----------
2019-02-03 07:15:25 [INFO ] Writing RPM manifest:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/compose/metadata/rpms.json
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: EXTRA_FILES ----------
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: CREATEREPO ----------
2019-02-03 07:15:25 [INFO ] Writing modules metadata:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/compose/metadata/modules.json
2019-02-03 07:15:25 [INFO ] Config section 'ostree' was not found. Skipping.
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: OSTREE ----------
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: PRODUCTIMG ----------
2019-02-03 07:15:25 [INFO ] Config section 'ostree_installer' was not found.
Skipping.
2019-02-03 07:15:25 [WARNING ] [SKIP ] ---------- PHASE: OSTREE_INSTALLER ----------
2019-02-03 07:15:25 [INFO ] [BEGIN] ---------- PHASE: CREATEISO ----------
2019-02-03 07:15:25 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'createiso', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:26 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'createiso-targets', {'deliverables': [], 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:28 [INFO ] Config section 'extra_isos' was not found.
Skipping.
2019-02-03 07:15:28 [WARNING ] [SKIP ] ---------- PHASE: EXTRA_ISOS ----------
2019-02-03 07:15:28 [INFO ] Config section 'live_images' was not found.
Skipping.
2019-02-03 07:15:28 [WARNING ] [SKIP ] ---------- PHASE: LIVE_IMAGES ----------
2019-02-03 07:15:28 [INFO ] [BEGIN] ---------- PHASE: IMAGE_BUILD ----------
2019-02-03 07:15:28 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'image_build', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:15:31 [INFO ] Config section 'live_media' was not found.
Skipping.
2019-02-03 07:15:31 [WARNING ] [SKIP ] ---------- PHASE: LIVE_MEDIA ----------
2019-02-03 07:15:31 [INFO ] Config section 'osbs' was not found. Skipping.
2019-02-03 07:15:31 [WARNING ] [SKIP ] ---------- PHASE: OSBS ----------
2019-02-03 07:15:31 [IMAGE_BUILD ] [INFO ] [BEGIN] Creating image (formats:
qcow2-raw-xz, arches: aarch64-ppc64le-s390x-x86_64, variant: Cloud, subvariant:
Cloud_Base)
2019-02-03 07:15:31 [IMAGE_BUILD ] [INFO ] [BEGIN] Creating image (formats:
vagrant-libvirt-vagrant-virtualbox, arches: x86_64, variant: Cloud, subvariant:
Cloud_Base)
2019-02-03 07:15:31 [IMAGE_BUILD ] [INFO ] Writing image-build config for
Cloud.aarch64-ppc64le-s390x-x86_64 into
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/image-build/Cloud/qcow2-raw-xz_Fedora-Cloud-Base_aarch64-ppc64le-s390x-x86_64.cfg
2019-02-03 07:15:31 [IMAGE_BUILD ] [INFO ] Writing image-build config for
Cloud.x86_64 into
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/work/image-build/Cloud/vagrant-libvirt-vagrant-virtualbox_Fedora-Cloud-Base-Vagrant_x86_64.cfg
2019-02-03 07:23:52 [IMAGE_BUILD ] [DEBUG ] build-image outputs: {'retcode':
1, 'output': 'Created task: 32498287\nTask info:
https://koji.fedoraproject.org/koji/taskinfo?taskID=32498287\nWatching tasks (this may be
safely interrupted)...\n32498287 image (f29, Fedora-Cloud-Base-29, fedora-cloud-base.ks):
free\n32498287 image (f29, Fedora-Cloud-Base-29, fedora-cloud-base.ks): free -> open
(buildhw-aarch64-03.arm.fedoraproject.org)\n 32498293 createImage (f29,
Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks, x86_64): open
(buildhw-02.phx2.fedoraproject.org)\n 32498290 createImage (f29,
Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks, ppc64le): open
(buildvm-ppc64le-02.ppc.fedoraproject.org)\n 32498289 createImage (f29,
Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks, aarch64): open
(buildhw-aarch64-02.arm.fedoraproject.org)\n 32498292 createImage (f29,
Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks, s390x): free\n 32498292
createImage (f29, Fedora-Cl
oud-Base-29-20190203.0, fedora-cloud-base.ks, s390x): free -> open
(buildvm-s390x-02.s390.fedoraproject.org)\n 32498292 createImage (f29,
Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks, s390x): open
(
buildvm-s390x-02.s390.fedoraproject.org) -> FAILED: ApplianceError: Image status is
FAILED: Did not receive a reply. Possible causes include: the remote application did not
send a reply, the message bus security policy blocked the reply, the reply timeout
expired, or the network connection was broken.\n 0 free 4 open 0 done 1
failed\n32498287 image (f29, Fedora-Cloud-Base-29, fedora-cloud-base.ks): open
(
buildhw-aarch64-03.arm.fedoraproject.org) -> FAILED: ApplianceError: Image status is
FAILED: Did not receive a reply. Possible causes include: the remote application did not
send a reply, the message bus security policy blocked the reply, the reply timeout
expired, or the network connection was broken.\n 0 free 3 open 0 done 2 failed\n
32498293 createImage (f29, Fedora-
Cloud-Base-29-20190203.0, fedora-cloud-base.ks, x86_64): open
(
buildhw-02.phx2.fedoraproject.org) -> canceled\n 0 free 2 open 1 done 2 failed\n
32498290 createImage (f29, Fedora-Cloud-Base-29-20190203.0, fedora-cloud-base.ks,
ppc64le): open (
buildvm-ppc64le-02.ppc.fedoraproject.org) -> canceled\n 0 free 1 open
2 done 2 failed\n 32498289 createImage (f29, Fedora-Cloud-Base-29-20190203.0,
fedora-cloud-base.ks, aarch64): open (
buildhw-aarch64-02.arm.fedoraproject.org) ->
canceled\n 0 free 0 open 3 done 2 failed\n\n32498287 image (f29, Fedora-Cloud-Base-29,
fedora-cloud-base.ks) failed\n', 'task_id': 32498287}
2019-02-03 07:23:52 [IMAGE_BUILD ] [ERROR ] CreateImageBuild failed.
2019-02-03 07:28:12 [IMAGE_BUILD ] [DEBUG ] build-image outputs: {'retcode':
0, 'output': 'Created task: 32498291\nTask info:
https://koji.fedoraproject.org/koji/taskinfo?taskID=32498291\nWatching tasks (this may be
safely interrupted)...\n32498291 image (f29, Fedora-Cloud-Base-Vagrant-29,
fedora-cloud-base-vagrant.ks): free\n32498291 image (f29, Fedora-Cloud-Base-Vagrant-29,
fedora-cloud-base-vagrant.ks): free -> open (buildhw-03.phx2.fedoraproject.org)\n
32498294 createImage (f29, Fedora-Cloud-Base-Vagrant-29-20190203.0,
fedora-cloud-base-vagrant.ks, x86_64): free\n 32498294 createImage (f29,
Fedora-Cloud-Base-Vagrant-29-20190203.0, fedora-cloud-base-vagrant.ks, x86_64): free ->
open (buildhw-03.phx2.fedoraproject.org)\n 32498294 createImage (f29,
Fedora-Cloud-Base-Vagrant-29-20190203.0, fedora-cloud-base-vagrant.ks, x86_64): open
(
buildhw-03.phx2.fedoraproject.org) -> closed\n 0 free 1 open 1 done 0 failed\n
32498442 tagBuild (noarch): closed\n32498291 image (f29, Fe
dora-Cloud-Base-Vagrant-29, fedora-cloud-base-vagrant.ks): open
(
buildhw-03.phx2.fedoraproject.org) -> closed\n 0 free 0 open 3 done 0
failed\n\n32498291 image (f29, Fedora-Cloud-Base-Vagrant-29, fedora-cloud-base-vagrant.ks)
completed successfully\n', 'task_id': 32498291}
2019-02-03 07:28:12 [IMAGE_BUILD ] [INFO ] Hardlinking
/mnt/koji/packages/Fedora-Cloud-Base-Vagrant/29/20190203.0/images/Fedora-Cloud-Base-Vagrant-29-20190203.0.x86_64.vagrant-virtualbox.box
to
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/compose/Cloud/x86_64/images/Fedora-Cloud-Base-Vagrant-29-20190203.0.x86_64.vagrant-virtualbox.box
2019-02-03 07:28:12 [IMAGE_BUILD ] [INFO ] Hardlinking
/mnt/koji/packages/Fedora-Cloud-Base-Vagrant/29/20190203.0/images/Fedora-Cloud-Base-Vagrant-29-20190203.0.x86_64.vagrant-libvirt.box
to
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/compose/Cloud/x86_64/images/Fedora-Cloud-Base-Vagrant-29-20190203.0.x86_64.vagrant-libvirt.box
2019-02-03 07:28:12 [IMAGE_BUILD ] [INFO ] [DONE ] Creating image (formats:
vagrant-libvirt-vagrant-virtualbox, arches: x86_64, variant: Cloud, subvariant:
Cloud_Base) (task id: 32498291)
2019-02-03 07:28:12 [INFO ] [DONE ] ---------- PHASE: CREATEISO ----------
2019-02-03 07:28:12 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-stop', {'phase_name': 'createiso', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
2019-02-03 07:28:14 [ERROR ] Compose run failed: ImageBuild task failed: 32498287. See
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/logs/aarch64-ppc64le-s390x-x86_64/imagebuild-Cloud-Cloud_Base-qcow2-raw-xz.aarch64-ppc64le-s390x-x86_64.log
for more details.
2019-02-03 07:28:14 [ERROR ] Extended traceback in:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0/logs/global/traceback.global.log
2019-02-03 07:28:14 [CRITICAL] Compose failed:
/mnt/koji/compose/cloud/Fedora-Cloud-29-20190203.0
2019-02-03 07:28:14 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'status-change', {'status': 'DOOMED', 'compose_id':
'Fedora-Cloud-29-20190203.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/cloud/Fedora-Cloud-29-20190203.0/compose',
'compose_date': '20190203', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20190203.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '29', 'release_type':
'ga', 'release_is_layered': False}
real 13m8.069s
user 0m13.731s
sys 0m1.529s