/tmp/CloudF28.kO5K7p ~
Cloning into 'pungi-fedora'...
Switched to a new branch 'f28'
Branch f28 set up to track remote branch f28 from origin.
cat: /mnt/koji/compose/latest-Fedora-Cloud-28/COMPOSE_ID: No such file or directory
Cloning into 'releng'...
WARNING: Config option gather_source was removed and has no effect; remove it.
2018-05-01 06:15:15 [INFO ] Automatically setting 'supported' flag due to
label: RC-20180501.0.
2018-05-01 06:15:15 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'status-change', {'status': 'STARTED', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:17 [INFO ] Host:
compose-x86-01.phx2.fedoraproject.org
2018-05-01 06:15:17 [INFO ] Pungi version: 4.1.23
2018-05-01 06:15:17 [INFO ] User name: root
2018-05-01 06:15:17 [INFO ] Working directory: /tmp/CloudF28.kO5K7p/pungi-fedora
2018-05-01 06:15:17 [INFO ] Command line: /usr/bin/pungi-koji
--notification-script=/usr/bin/pungi-fedmsg-notification --config=fedora-cloud.conf
--old-composes=/mnt/koji/compose --label=RC-20180501.0 --target-dir=/mnt/koji/compose
2018-05-01 06:15:17 [INFO ] Compose top directory:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0
2018-05-01 06:15:17 [INFO ] Current timezone offset: +00:00
2018-05-01 06:15:17 [DEBUG ] Writing variants file:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/global/variants.xml
2018-05-01 06:15:17 [DEBUG ] Exporting file
/tmp/CloudF28.kO5K7p/pungi-fedora/variants-fedora.xml from current working directory...
2018-05-01 06:15:17 [INFO ] Excluding variant AtomicHost: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Container: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Everything: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Labs: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Server: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Spins: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Workstation: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant AtomicWorkstation: filtered by
configuration.
2018-05-01 06:15:17 [INFO ] Excluding variant Modular: filtered by configuration.
2018-05-01 06:15:17 [INFO ] Config section 'live_images' was not found.
Skipping.
2018-05-01 06:15:17 [INFO ] Config section 'live_media' was not found.
Skipping.
2018-05-01 06:15:17 [INFO ] Config section 'ostree' was not found. Skipping.
2018-05-01 06:15:17 [INFO ] Config section 'ostree_installer' was not found.
Skipping.
2018-05-01 06:15:17 [INFO ] Config section 'osbs' was not found. Skipping.
2018-05-01 06:15:17 [INFO ] [BEGIN] ---------- PHASE: INIT ----------
2018-05-01 06:15:17 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'init', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:19 [DEBUG ] Writing global comps file:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/global/comps/comps-global.xml
2018-05-01 06:15:19 [DEBUG ] Exporting file comps-f28.xml from git
https://pagure.io/fedora-comps.git (branch master)...
2018-05-01 06:15:23 [DEBUG ] Running "make comps-f28.xml"
2018-05-01 06:15:28 [DEBUG ] Writing comps file for arch 'aarch64':
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/aarch64/comps/comps-aarch64.xml
2018-05-01 06:15:29 [INFO ] [BEGIN] Creating comps repo for arch 'aarch64'
2018-05-01 06:15:29 [INFO ] [DONE ] Creating comps repo for arch 'aarch64'
2018-05-01 06:15:29 [DEBUG ] Writing comps file for arch 'ppc64':
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/ppc64/comps/comps-ppc64.xml
2018-05-01 06:15:29 [INFO ] [BEGIN] Creating comps repo for arch 'ppc64'
2018-05-01 06:15:30 [INFO ] [DONE ] Creating comps repo for arch 'ppc64'
2018-05-01 06:15:30 [DEBUG ] Writing comps file for arch 'ppc64le':
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/ppc64le/comps/comps-ppc64le.xml
2018-05-01 06:15:30 [INFO ] [BEGIN] Creating comps repo for arch 'ppc64le'
2018-05-01 06:15:30 [INFO ] [DONE ] Creating comps repo for arch 'ppc64le'
2018-05-01 06:15:30 [DEBUG ] Writing comps file for arch 's390x':
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/s390x/comps/comps-s390x.xml
2018-05-01 06:15:30 [INFO ] [BEGIN] Creating comps repo for arch 's390x'
2018-05-01 06:15:30 [INFO ] [DONE ] Creating comps repo for arch 's390x'
2018-05-01 06:15:30 [DEBUG ] Writing comps file for arch 'x86_64':
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/x86_64/comps/comps-x86_64.xml
2018-05-01 06:15:31 [INFO ] [BEGIN] Creating comps repo for arch 'x86_64'
2018-05-01 06:15:31 [INFO ] [DONE ] Creating comps repo for arch 'x86_64'
2018-05-01 06:15:31 [DEBUG ] Copying original comps file (arch: aarch64, variant:
Cloud):
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/aarch64/comps/comps-Cloud.aarch64.xml
2018-05-01 06:15:31 [DEBUG ] Copying original comps file (arch: ppc64, variant: Cloud):
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/ppc64/comps/comps-Cloud.ppc64.xml
2018-05-01 06:15:31 [DEBUG ] Copying original comps file (arch: ppc64le, variant:
Cloud):
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/ppc64le/comps/comps-Cloud.ppc64le.xml
2018-05-01 06:15:31 [DEBUG ] Copying original comps file (arch: s390x, variant: Cloud):
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/s390x/comps/comps-Cloud.s390x.xml
2018-05-01 06:15:31 [DEBUG ] Copying original comps file (arch: x86_64, variant: Cloud):
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/x86_64/comps/comps-Cloud.x86_64.xml
2018-05-01 06:15:31 [INFO ] [DONE ] ---------- PHASE: INIT ----------
2018-05-01 06:15:31 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-stop', {'phase_name': 'init', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: PKGSET ----------
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: BUILDINSTALL ----------
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: GATHER ----------
2018-05-01 06:15:33 [INFO ] Writing RPM manifest:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/compose/metadata/rpms.json
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: EXTRA_FILES ----------
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: CREATEREPO ----------
2018-05-01 06:15:33 [INFO ] Writing modules metadata:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/compose/metadata/modules.json
2018-05-01 06:15:33 [INFO ] Config section 'ostree' was not found. Skipping.
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: OSTREE ----------
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: PRODUCTIMG ----------
2018-05-01 06:15:33 [INFO ] Config section 'ostree_installer' was not found.
Skipping.
2018-05-01 06:15:33 [WARNING ] [SKIP ] ---------- PHASE: OSTREE_INSTALLER ----------
2018-05-01 06:15:33 [INFO ] [BEGIN] ---------- PHASE: CREATEISO ----------
2018-05-01 06:15:33 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'createiso', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:34 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'createiso-targets', {'deliverables': [], 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:36 [INFO ] Config section 'live_images' was not found.
Skipping.
2018-05-01 06:15:36 [WARNING ] [SKIP ] ---------- PHASE: LIVE_IMAGES ----------
2018-05-01 06:15:36 [INFO ] [BEGIN] ---------- PHASE: IMAGE_BUILD ----------
2018-05-01 06:15:36 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-start', {'phase_name': 'image_build', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:15:39 [INFO ] Config section 'live_media' was not found.
Skipping.
2018-05-01 06:15:39 [IMAGE_BUILD ] [INFO ] [BEGIN] Creating image (formats:
qcow2-raw-xz, arches: aarch64-ppc64-ppc64le-s390x-x86_64, variant: Cloud, subvariant:
Cloud_Base)
2018-05-01 06:15:39 [WARNING ] [SKIP ] ---------- PHASE: LIVE_MEDIA ----------
2018-05-01 06:15:39 [IMAGE_BUILD ] [INFO ] [BEGIN] Creating image (formats:
vagrant-libvirt-vagrant-virtualbox, arches: x86_64, variant: Cloud, subvariant:
Cloud_Base)
2018-05-01 06:15:39 [INFO ] Config section 'osbs' was not found. Skipping.
2018-05-01 06:15:39 [WARNING ] [SKIP ] ---------- PHASE: OSBS ----------
2018-05-01 06:15:39 [IMAGE_BUILD ] [INFO ] Writing image-build config for
Cloud.aarch64-ppc64-ppc64le-s390x-x86_64 into
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/image-build/Cloud/qcow2-raw-xz_Fedora-Cloud-Base_aarch64-ppc64-ppc64le-s390x-x86_64.cfg
2018-05-01 06:15:39 [IMAGE_BUILD ] [INFO ] Writing image-build config for
Cloud.x86_64 into
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/work/image-build/Cloud/vagrant-libvirt-vagrant-virtualbox_Fedora-Cloud-Base-Vagrant_x86_64.cfg
2018-05-01 06:16:08 [IMAGE_BUILD ] [DEBUG ] build-image outputs: {'retcode':
1, 'output': "Created task: 26694306\nTask info:
https://koji.fedoraproject.org/koji/taskinfo?taskID=26694306\nWatching tasks (this may be
safely interrupted)...\n26694306 image (f28, Fedora-Cloud-Base-28, fedora-cloud-base.ks):
free\n26694306 image (f28, Fedora-Cloud-Base-28, fedora-cloud-base.ks): free -> open
(buildhw-aarch64-03.arm.fedoraproject.org)\n 26694312 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, s390x): open
(buildvm-s390x-02.s390.fedoraproject.org)\n 26694309 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, aarch64): open
(buildhw-aarch64-02.arm.fedoraproject.org)\n 26694311 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, ppc64le): open
(buildvm-ppc64le-01.ppc.fedoraproject.org)\n 26694313 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, x86_64): free\n 26694310
createImage (f28, Fed
ora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, ppc64): free\n 26694313 createImage
(f28, Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, x86_64): free -> open
(buildhw-04.phx2.fedoraproject.org)\n 26694310 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, ppc64): free -> open
(buildvm-ppc64-01.ppc.fedoraproject.org)\n 26694312 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, s390x): open
(
buildvm-s390x-02.s390.fedoraproject.org) -> FAILED: ApplianceError: Image status is
FAILED: Cannot connect to libvirt. Make sure libvirt is running. [Original message:
Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or
directory]\n 0 free 5 open 0 done 1 failed\n26694306 image (f28, Fedora-Cloud-Base-28,
fedora-cloud-base.ks): open (
buildhw-aarch64-03.arm.fedoraproject.org) -> FAILED:
ApplianceError: Image status is FAILED: Cannot connect to libvirt. Make sure libvirt is
running. [Original message: Failed
to connect socket to '/var/run/libvirt/libvirt-sock': No such file or
directory]\n 0 free 4 open 0 done 2 failed\n 26694309 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, aarch64): open
(
buildhw-aarch64-02.arm.fedoraproject.org) -> canceled\n 0 free 3 open 1 done 2
failed\n 26694311 createImage (f28, Fedora-Cloud-Base-28-20180501.0,
fedora-cloud-base.ks, ppc64le): open (
buildvm-ppc64le-01.ppc.fedoraproject.org) ->
canceled\n 0 free 2 open 2 done 2 failed\n 26694313 createImage (f28,
Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, x86_64): open
(
buildhw-04.phx2.fedoraproject.org) -> canceled\n 0 free 1 open 3 done 2 failed\n
26694310 createImage (f28, Fedora-Cloud-Base-28-20180501.0, fedora-cloud-base.ks, ppc64):
open (
buildvm-ppc64-01.ppc.fedoraproject.org) -> canceled\n 0 free 0 open 4 done 2
failed\n\n26694306 image (f28, Fedora-Cloud-Base-28, fedora-cloud-base.ks) failed\n",
'task_id': 26694306}
2018-05-01 06:16:09 [IMAGE_BUILD ] [ERROR ] CreateImageBuild failed.
2018-05-01 06:27:12 [IMAGE_BUILD ] [DEBUG ] build-image outputs: {'retcode':
0, 'output': 'Created task: 26694307\nTask info:
https://koji.fedoraproject.org/koji/taskinfo?taskID=26694307\nWatching tasks (this may be
safely interrupted)...\n26694307 image (f28, Fedora-Cloud-Base-Vagrant-28,
fedora-cloud-base-vagrant.ks): free\n26694307 image (f28, Fedora-Cloud-Base-Vagrant-28,
fedora-cloud-base-vagrant.ks): free -> open
(buildhw-aarch64-03.arm.fedoraproject.org)\n 26694314 createImage (f28,
Fedora-Cloud-Base-Vagrant-28-20180501.0, fedora-cloud-base-vagrant.ks, x86_64): free\n
26694314 createImage (f28, Fedora-Cloud-Base-Vagrant-28-20180501.0,
fedora-cloud-base-vagrant.ks, x86_64): free -> open
(buildhw-09.phx2.fedoraproject.org)\n 26694314 createImage (f28,
Fedora-Cloud-Base-Vagrant-28-20180501.0, fedora-cloud-base-vagrant.ks, x86_64): open
(
buildhw-09.phx2.fedoraproject.org) -> closed\n 0 free 1 open 1 done 0 failed\n
26694584 tagBuild (noarch): closed\n26694307 image (
f28, Fedora-Cloud-Base-Vagrant-28, fedora-cloud-base-vagrant.ks): open
(
buildhw-aarch64-03.arm.fedoraproject.org) -> closed\n 0 free 0 open 3 done 0
failed\n\n26694307 image (f28, Fedora-Cloud-Base-Vagrant-28, fedora-cloud-base-vagrant.ks)
completed successfully\n', 'task_id': 26694307}
2018-05-01 06:27:12 [IMAGE_BUILD ] [INFO ] Hardlinking
/mnt/koji/packages/Fedora-Cloud-Base-Vagrant/28/20180501.0/images/Fedora-Cloud-Base-Vagrant-28-20180501.0.x86_64.vagrant-virtualbox.box
to
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/compose/Cloud/x86_64/images/Fedora-Cloud-Base-Vagrant-28-20180501.0.x86_64.vagrant-virtualbox.box
2018-05-01 06:27:12 [IMAGE_BUILD ] [INFO ] Hardlinking
/mnt/koji/packages/Fedora-Cloud-Base-Vagrant/28/20180501.0/images/Fedora-Cloud-Base-Vagrant-28-20180501.0.x86_64.vagrant-libvirt.box
to
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/compose/Cloud/x86_64/images/Fedora-Cloud-Base-Vagrant-28-20180501.0.x86_64.vagrant-libvirt.box
2018-05-01 06:27:12 [IMAGE_BUILD ] [INFO ] [DONE ] Creating image (formats:
vagrant-libvirt-vagrant-virtualbox, arches: x86_64, variant: Cloud, subvariant:
Cloud_Base) (task id: 26694307)
2018-05-01 06:27:12 [INFO ] [DONE ] ---------- PHASE: CREATEISO ----------
2018-05-01 06:27:12 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'phase-stop', {'phase_name': 'createiso', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
2018-05-01 06:27:14 [ERROR ] Compose run failed: ImageBuild task failed: 26694306. See
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/logs/aarch64-ppc64-ppc64le-s390x-x86_64/imagebuild-Cloud-Cloud_Base-qcow2-raw-xz.aarch64-ppc64-ppc64le-s390x-x86_64.log
for more details.
2018-05-01 06:27:14 [ERROR ] Extended traceback in:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0/logs/global/traceback.global.log
2018-05-01 06:27:14 [CRITICAL] Compose failed:
/mnt/koji/compose/Fedora-Cloud-28-20180501.0
2018-05-01 06:27:14 [DEBUG ] Notification: '/usr/bin/pungi-fedmsg-notification'
'status-change', {'status': 'DOOMED', 'compose_id':
'Fedora-Cloud-28-20180501.0', 'location':
'http://kojipkgs.fedoraproject.org/compose/Fedora-Cloud-28-20180501.0/compose',
'compose_date': '20180501', 'compose_type': 'production',
'compose_respin': 0, 'compose_label': 'RC-20180501.0',
'release_short': 'Fedora-Cloud', 'release_name':
'Fedora-Cloud', 'release_version': '28', 'release_type':
'ga', 'release_is_layered': False}
real 12m1.361s
user 0m13.762s
sys 0m1.829s