============================== lbuild-nightly.sh: failure at Thu Jan 24 03:32:44 CET 2019 == dummy_vnode01 == [=None] not (yet?) running @ 2019-01-24:03-32 * 03:32:10 -- ********** 1 RUNNING step show on plc plctest1_vplc01 + ======== site main site for plc number 1 + nodes : vnode01.pl.sophia.inria.fr + node=node1 host_box=boxtops hostname= vnode01.pl.sophia.inria.fr ip= 138.96.112.101 * 03:32:10 -- ********** 1 SUCCESSFUL step show on plctest1_vplc01 TRACE: 1 03-32-10->0s=0:00:00.000723s status=OK step=show plc=plctest1_vplc01 force=False * 03:32:10 -- ********** 1 RUNNING step plcvm_delete on plc plctest1_vplc01 -> 03:32:10 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks mkdir -p 2019.01.24--f27 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 03:32:10 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2019.01.24--f27 ';' rm -f /vservers/2019.01.24--f27-1-vplc01/2019.01.24--f27-1-vplc01.timestamp Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 03:32:11 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2019.01.24--f27 ';' virsh -c lxc:/// destroy 2019.01.24--f27-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. error: failed to get domain '2019.01.24--f27-1-vplc01' error: Domain not found: No domain with matching name '2019.01.24--f27-1-vplc01' -> 03:32:11 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2019.01.24--f27 ';' virsh -c lxc:/// undefine 2019.01.24--f27-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. error: failed to get domain '2019.01.24--f27-1-vplc01' error: Domain not found: No domain with matching name '2019.01.24--f27-1-vplc01' -> 03:32:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2019.01.24--f27 ';' rm -fr /vservers/2019.01.24--f27-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. * 03:32:12 -- ********** 1 SUCCESSFUL step plcvm_delete on plctest1_vplc01 TRACE: 1 03-32-10->2s=0:00:02.014017s status=OK step=plcvm_delete plc=plctest1_vplc01 force=False * 03:32:12 -- ********** 1 RUNNING step plcvm_timestamp on plc plctest1_vplc01 -> 03:32:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks mkdir -p /vservers/2019.01.24--f27-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 03:32:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks echo 1548297132 '>' /vservers/2019.01.24--f27-1-vplc01/2019.01.24--f27-1-vplc01.timestamp Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. * 03:32:13 -- ********** 1 SUCCESSFUL step plcvm_timestamp on plctest1_vplc01 TRACE: 1 03-32-12->0s=0:00:00.740896s status=OK step=plcvm_timestamp plc=plctest1_vplc01 force=False * 03:32:13 -- ********** 1 RUNNING step plcvm_create on plc plctest1_vplc01 -> 03:32:13 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks rm -rf 2019.01.24--f27/build Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 03:32:13 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -r build buzzcocks:2019.01.24--f27/build -> 03:32:14 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2019.01.24--f27 ';' build/lbuild-initvm.sh -p linux64 -d lxc -f f27 -r http://build.onelab.eu/lxc//2019.01.24--f27/RPMS -n vplc01.pl.sophia.inria.fr 2019.01.24--f27-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Bridge already set up - skipping create_bridge_if_needed + set -e + local lxc=2019.01.24--f27-1-vplc01 + shift + local fcdistro=f27 + shift + local pldistro=lxc + shift + local personality=linux64 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 ++ package_method f27 ++ local fcdistro=f27 ++ shift ++ case $fcdistro in ++ echo dnf + pkg_method=dnf + case $pkg_method in + '[' -z '' ']' + fedora_install 2019.01.24--f27-1-vplc01 + set -x + set -e + local lxc=2019.01.24--f27-1-vplc01 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + local cache=/var/cache/lxc/fedora/x86_64/27 + mkdir -p /var/cache/lxc/fedora/x86_64/27 + flock --exclusive --timeout 60 200 Updating cache /var/cache/lxc/fedora/x86_64/27/rootfs ... + '[' '!' -e /var/cache/lxc/fedora/x86_64/27/rootfs ']' + echo 'Updating cache /var/cache/lxc/fedora/x86_64/27/rootfs ...' + dnf --installroot /var/cache/lxc/fedora/x86_64/27/rootfs --releasever=27 -y --nogpgcheck update Fedora 27 - x86_64 - Updates 16 kB/s | 24 kB 00:01 Dependencies resolved. Nothing to do. Complete! Update finished Filling /vservers/2019.01.24--f27-1-vplc01 from /var/cache/lxc/fedora/x86_64/27/rootfs ... + echo 'Update finished' + echo 'Filling /vservers/2019.01.24--f27-1-vplc01 from /var/cache/lxc/fedora/x86_64/27/rootfs ... ' + rsync -a /var/cache/lxc/fedora/x86_64/27/rootfs/ /vservers/2019.01.24--f27-1-vplc01/ + return 0 + return 0 ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 rm -rf /var/lib/rpm/__db.000 /var/lib/rpm/__db.001 /var/lib/rpm/__db.002 /var/lib/rpm/__db.003 /var/lib/rpm/__db.004 /var/lib/rpm/__db.005 /var/lib/rpm/__db.006 /var/lib/rpm/__db.007 /var/lib/rpm/__db.008 /var/lib/rpm/__db.009 ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 rpm --rebuilddb + fedora_configure 2019.01.24--f27-1-vplc01 f27 + set -x + set -e + local lxc=2019.01.24--f27-1-vplc01 + shift + local fcdistro=f27 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + mkdir -p /vservers/2019.01.24--f27-1-vplc01/selinux + echo 0 + cat + cat + local dev_path=/vservers/2019.01.24--f27-1-vplc01/dev + rm -rf /vservers/2019.01.24--f27-1-vplc01/dev + mkdir -p /vservers/2019.01.24--f27-1-vplc01/dev + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/null c 1 3 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/zero c 1 5 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/random c 1 8 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/urandom c 1 9 + mkdir -m 755 /vservers/2019.01.24--f27-1-vplc01/dev/pts + mkdir -m 1777 /vservers/2019.01.24--f27-1-vplc01/dev/shm + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty c 5 0 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty0 c 4 0 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty1 c 4 1 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty2 c 4 2 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty3 c 4 3 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/tty4 c 4 4 + mknod -m 600 /vservers/2019.01.24--f27-1-vplc01/dev/console c 5 1 + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/full c 1 7 + mknod -m 600 /vservers/2019.01.24--f27-1-vplc01/dev/initctl p + mknod -m 666 /vservers/2019.01.24--f27-1-vplc01/dev/ptmx c 5 2 + fedora_configure_systemd 2019.01.24--f27-1-vplc01 + set -e + set -x + local lxc=2019.01.24--f27-1-vplc01 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + '[' -d /vservers/2019.01.24--f27-1-vplc01/etc/systemd ']' + ln -sf /lib/systemd/system/multi-user.target /vservers/2019.01.24--f27-1-vplc01/etc/systemd/system/default.target + touch /vservers/2019.01.24--f27-1-vplc01/etc/fstab + ln -sf /dev/null /vservers/2019.01.24--f27-1-vplc01/etc/systemd/system/udev.service + ln -sf /dev/null /vservers/2019.01.24--f27-1-vplc01/etc/systemd/system/getty@.service + rm -f /vservers/2019.01.24--f27-1-vplc01/etc/systemd/system/getty.target.wants/getty@tty1.service + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 chkconfig network on error reading information on service network: No such file or directory + local guest_ifcfg=/vservers/2019.01.24--f27-1-vplc01/etc/sysconfig/network-scripts/ifcfg-eth0 ++ dirname /vservers/2019.01.24--f27-1-vplc01/etc/sysconfig/network-scripts/ifcfg-eth0 + mkdir -p /vservers/2019.01.24--f27-1-vplc01/etc/sysconfig/network-scripts + '[' -n '' ']' + write_guest_ifcfg_publicip + cat + '[' -z '' ']' + fedora_configure_yum 2019.01.24--f27-1-vplc01 f27 lxc + set -x + set -e + trap failure ERR INT + local lxc=2019.01.24--f27-1-vplc01 + shift + local fcdistro=f27 + shift + local pldistro=lxc + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 rpm --rebuilddb Initializing yum.repos.d in 2019.01.24--f27-1-vplc01 + echo 'Initializing yum.repos.d in 2019.01.24--f27-1-vplc01' + rm -f /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora-cisco-openh264.repo /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora.repo /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora.repo.rpmnew /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora-updates.repo /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora-updates.repo.rpmnew /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/fedora-updates-testing.repo + local repofile=/vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + yumconf_mirrors /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo build f27 '' http://mirror.onelab.eu/ + dest_yumconf=/vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + shift + builddir=build + shift + fcdistro=f27 + shift + yumexclude_line= + shift + mirrors=http://mirror.onelab.eu/ + template=build/mirroring/f27/yum.repos.d/building.repo.in + '[' '!' -f build/mirroring/f27/yum.repos.d/building.repo.in ']' + for mirror in $mirrors + yumconf_mirror /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo build/mirroring/f27/yum.repos.d/building.repo.in '' http://mirror.onelab.eu/ + dest_yumconf=/vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + shift + template=build/mirroring/f27/yum.repos.d/building.repo.in + shift + yumexclude_line= + shift + mirror=http://mirror.onelab.eu/ + shift + sed -e s,@MIRRORURL@,http://mirror.onelab.eu/,g -e '/baseurl=/i\ ' build/mirroring/f27/yum.repos.d/building.repo.in ++ grep '^baseurl=' /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + baseurl_defs='baseurl=http://mirror.onelab.eu//fedora/releases/27/Everything/$basearch/os/ baseurl=http://mirror.onelab.eu//fedora/updates/27/$basearch/' + '[' -z 'baseurl=http://mirror.onelab.eu//fedora/releases/27/Everything/$basearch/os/ baseurl=http://mirror.onelab.eu//fedora/updates/27/$basearch/' ']' + for baseurl_def in $baseurl_defs ++ echo 'baseurl=http://mirror.onelab.eu//fedora/releases/27/Everything/$basearch/os/' ++ sed -e s,baseurl=,, -e 's,$basearch,x86_64,g' * Trying to fetch http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os//repodata/repomd.xml + baseurl=http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os/ + repomd=http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os//repodata/repomd.xml + echo '* Trying to fetch http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os//repodata/repomd.xml' + build_fetch http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os//repodata/repomd.xml + curl --fail --silent --max-time 60 --output /dev/null http://mirror.onelab.eu//fedora/releases/27/Everything/x86_64/os//repodata/repomd.xml + for baseurl_def in $baseurl_defs ++ echo 'baseurl=http://mirror.onelab.eu//fedora/updates/27/$basearch/' ++ sed -e s,baseurl=,, -e 's,$basearch,x86_64,g' * Trying to fetch http://mirror.onelab.eu//fedora/updates/27/x86_64//repodata/repomd.xml + baseurl=http://mirror.onelab.eu//fedora/updates/27/x86_64/ + repomd=http://mirror.onelab.eu//fedora/updates/27/x86_64//repodata/repomd.xml + echo '* Trying to fetch http://mirror.onelab.eu//fedora/updates/27/x86_64//repodata/repomd.xml' + build_fetch http://mirror.onelab.eu//fedora/updates/27/x86_64//repodata/repomd.xml + curl --fail --silent --max-time 60 --output /dev/null http://mirror.onelab.eu//fedora/updates/27/x86_64//repodata/repomd.xml * Selected mirror http://mirror.onelab.eu/ + echo '* Selected mirror http://mirror.onelab.eu/' + return 0 + return 0 + sed -i /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo -e 's,gpgkey=.*,gpgkey=http://mirror.onelab.eu/keys//RPM-GPG-KEY-fedora-27-primary,' + rpm --root /vservers/2019.01.24--f27-1-vplc01 --import http://mirror.onelab.eu/keys//RPM-GPG-KEY-fedora-27-primary + '[' -n http://build.onelab.eu/lxc//2019.01.24--f27/RPMS ']' + '[' '!' -d /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d ']' ++ pl_plcyumexclude f27 lxc build ++ pl_yumexclude plcyumexclude f27 lxc build ++ keyword=plcyumexclude ++ shift ++ fcdistro=f27 ++ shift ++ pldistro=lxc ++ shift ++ builddir=build ++ shift +++ pl_locateDistroFile build lxc yumexclude.pkgs +++ builddir=build +++ shift +++ pldistro=lxc +++ shift +++ pkgsfile=yumexclude.pkgs +++ shift +++ pkgspath= +++ configdir=build/config.lxc +++ '[' '!' -d build/config.lxc ']' +++ paths='build/config.lxc/yumexclude.pkgs build/config.planetlab/yumexclude.pkgs' +++ for path in $paths +++ '[' -f build/config.lxc/yumexclude.pkgs ']' +++ pkgspath=build/config.lxc/yumexclude.pkgs +++ break +++ '[' -z build/config.lxc/yumexclude.pkgs ']' +++ echo 'pl_locateDistroFile: using build/config.lxc/yumexclude.pkgs' pl_locateDistroFile: using build/config.lxc/yumexclude.pkgs +++ echo build/config.lxc/yumexclude.pkgs +++ return 0 ++ yumexclude_file=build/config.lxc/yumexclude.pkgs ++ type -p pkgs.py ++ pkgs.py -a x86_64 -f f27 -d lxc plcyumexclude build/config.lxc/yumexclude.pkgs + yumexclude=drupal + for repo in $lxc_root/etc/yum.repos.d/* + '[' -f /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo ']' + yumconf_exclude /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo exclude=drupal + repo=/vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + shift + yumexclude_line=exclude=drupal + shift + sed -i -e '/#baseurl=.*$/i\ exclude=drupal' /vservers/2019.01.24--f27-1-vplc01/etc/yum.repos.d/building.repo + cat + return 0 + '[' -d /vservers/2019.01.24--f27-1-vplc01/cgroup ']' + mkdir /vservers/2019.01.24--f27-1-vplc01/cgroup + '[' -h /vservers/2019.01.24--f27-1-vplc01/etc/resolv.conf ']' + cp /etc/resolv.conf /vservers/2019.01.24--f27-1-vplc01/etc/resolv.conf + '[' -f /vservers/2019.01.24--f27-1-vplc01/etc/hosts ']' + mkdir -p /vservers/2019.01.24--f27-1-vplc01/root/.ssh + cat /root/.ssh/id_rsa.pub + chmod 700 /vservers/2019.01.24--f27-1-vplc01/root/.ssh + chmod 600 /vservers/2019.01.24--f27-1-vplc01/root/.ssh/authorized_keys + local config_xml=/tmp/2019.01.24--f27-1-vplc01.xml + '[' -n '' ']' + write_lxc_xml_publicip 2019.01.24--f27-1-vplc01 + local lxc=2019.01.24--f27-1-vplc01 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + cat + virsh -c lxc:/// define /tmp/2019.01.24--f27-1-vplc01.xml Domain 2019.01.24--f27-1-vplc01 defined from /tmp/2019.01.24--f27-1-vplc01.xml + return 0 + devel_or_test_tools 2019.01.24--f27-1-vplc01 f27 lxc linux64 + set -x + set -e + trap failure ERR INT + local lxc=2019.01.24--f27-1-vplc01 + shift + local fcdistro=f27 + shift + local pldistro=lxc + shift + local personality=linux64 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 ++ package_method f27 ++ local fcdistro=f27 ++ shift ++ case $fcdistro in ++ echo dnf + local pkg_method=dnf ++ pl_locateDistroFile build lxc runtime.pkgs ++ builddir=build ++ shift ++ pldistro=lxc ++ shift ++ pkgsfile=runtime.pkgs ++ shift ++ pkgspath= ++ configdir=build/config.lxc ++ '[' '!' -d build/config.lxc ']' ++ paths='build/config.lxc/runtime.pkgs build/config.planetlab/runtime.pkgs' ++ for path in $paths ++ '[' -f build/config.lxc/runtime.pkgs ']' ++ for path in $paths ++ '[' -f build/config.planetlab/runtime.pkgs ']' ++ pkgspath=build/config.planetlab/runtime.pkgs ++ break ++ '[' -z build/config.planetlab/runtime.pkgs ']' ++ echo 'pl_locateDistroFile: using build/config.planetlab/runtime.pkgs' pl_locateDistroFile: using build/config.planetlab/runtime.pkgs ++ echo build/config.planetlab/runtime.pkgs ++ return 0 + local pkgsfile=build/config.planetlab/runtime.pkgs ++ chroot /vservers/2019.01.24--f27-1-vplc01 linux64 uname -i + local lxc_arch=x86_64 + '[' x86_64 = unknown ']' ++ pl_getPackages -a x86_64 f27 lxc build/config.planetlab/runtime.pkgs ++ pl_parsePkgs package -a x86_64 f27 lxc build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=package ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f27 ++ shift ++ pldistro=lxc ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f27 -d lxc package build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f27 -d lxc package build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f27 -d lxc package build/config.planetlab/runtime.pkgs + local 'packages=NetworkManager bind-utils curl emacs-nox file openssh-clients psmisc yum-utils' ++ pl_getGroups -a x86_64 f27 lxc build/config.planetlab/runtime.pkgs ++ pl_parsePkgs group -a x86_64 f27 lxc build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=group ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f27 ++ shift ++ pldistro=lxc ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f27 -d lxc group build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f27 -d lxc group build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f27 -d lxc group build/config.planetlab/runtime.pkgs + local groups= + case "$pkg_method" in + local has_dnf= + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 dnf --version 2.7.5 Installed: dnf-0:2.7.5-2.fc27.noarch at Wed 09 Jan 2019 09:28:10 AM GMT Built : Fedora Project at Wed 29 Nov 2017 09:48:50 AM GMT Installed: rpm-0:4.14.2.1-1.fc27.x86_64 at Wed 09 Jan 2019 09:28:06 AM GMT Built : Fedora Project at Mon 22 Oct 2018 01:28:00 PM GMT + has_dnf=true + '[' -n true ']' container has dnf - invoking with --allowerasing + echo 'container has dnf - invoking with --allowerasing' + local 'pkg_installer=dnf -y install --allowerasing' + local 'grp_installer=dnf -y groupinstall --allowerasing' + '[' -n 'NetworkManager bind-utils curl emacs-nox file openssh-clients psmisc yum-utils' ']' + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 dnf -y install --allowerasing NetworkManager bind-utils curl emacs-nox file openssh-clients psmisc yum-utils Fedora 27 - x86_64 86 MB/s | 58 MB 00:00 Fedora 27 - x86_64 - Updates 87 MB/s | 27 MB 00:00 MyPLC 43 MB/s | 264 kB 00:00 Last metadata expiration check: 0:00:00 ago on Thu 24 Jan 2019 02:32:22 AM UTC. Package curl-7.55.1-14.fc27.x86_64 is already installed, skipping. Package openssh-clients-7.6p1-6.fc27.x86_64 is already installed, skipping. Dependencies resolved. ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: NetworkManager x86_64 1:1.8.8-2.fc27 updates 1.6 M bind-utils x86_64 32:9.11.4-3.P2.fc27 updates 424 k emacs-nox x86_64 1:25.3-4.fc27 updates 2.6 M file x86_64 5.31-12.fc27 updates 72 k psmisc x86_64 23.1-2.fc27 fedora 148 k yum-utils noarch 1.1.31-514.fc27 updates 118 k Installing dependencies: NetworkManager-libnm x86_64 1:1.8.8-2.fc27 updates 1.2 M alsa-lib x86_64 1.1.5-1.fc27 updates 423 k bind-libs x86_64 32:9.11.4-3.P2.fc27 updates 162 k bind-libs-lite x86_64 32:9.11.4-3.P2.fc27 updates 1.1 M bind-license noarch 32:9.11.4-3.P2.fc27 updates 93 k cpio x86_64 2.12-6.fc27 fedora 262 k emacs-common x86_64 1:25.3-4.fc27 updates 38 M gpm-libs x86_64 1.20.7-10.fc26 fedora 36 k jansson x86_64 2.11-1.fc27 updates 44 k libjpeg-turbo x86_64 1.5.3-1.fc27 updates 153 k liblockfile x86_64 1.09-7.fc27 fedora 28 k libndp x86_64 1.6-4.fc27 fedora 36 k libnl3 x86_64 3.4.0-1.fc27 updates 304 k polkit-libs x86_64 0.113-16.fc27 updates 72 k pyliblzma x86_64 0.5.3-19.fc27 fedora 54 k python-urlgrabber noarch 3.10.1-12.fc27 fedora 111 k python2 x86_64 2.7.15-4.fc27 updates 101 k python2-iniparse noarch 0.4-26.fc27 fedora 46 k python2-kitchen noarch 1.2.4-7.fc27 fedora 121 k python2-libs x86_64 2.7.15-4.fc27 updates 6.3 M python2-pip noarch 9.0.3-2.fc27 updates 2.0 M python2-pycurl x86_64 7.43.0-9.fc27 fedora 247 k python2-pygpgme x86_64 0.3-25.fc27 fedora 92 k python2-rpm x86_64 4.14.2.1-1.fc27 updates 118 k python2-setuptools noarch 37.0.0-2.fc27 updates 606 k python2-six noarch 1.11.0-1.fc27 fedora 36 k python3-bind noarch 32:9.11.4-3.P2.fc27 updates 140 k python3-ply noarch 3.9-4.fc27 fedora 106 k pyxattr x86_64 0.5.3-12.fc27 fedora 34 k yum noarch 3.4.3-513.fc27 fedora 1.2 M yum-metadata-parser x86_64 1.1.4-20.fc27 fedora 39 k Transaction Summary ================================================================================ Install 37 Packages Total download size: 58 M Installed size: 167 M Downloading Packages: (1/37): libndp-1.6-4.fc27.x86_64.rpm 11 MB/s | 36 kB 00:00 (2/37): psmisc-23.1-2.fc27.x86_64.rpm 23 MB/s | 148 kB 00:00 (3/37): bind-utils-9.11.4-3.P2.fc27.x86_64.rpm 40 MB/s | 424 kB 00:00 (4/37): bind-libs-9.11.4-3.P2.fc27.x86_64.rpm 29 MB/s | 162 kB 00:00 (5/37): NetworkManager-libnm-1.8.8-2.fc27.x86_6 27 MB/s | 1.2 MB 00:00 (6/37): NetworkManager-1.8.8-2.fc27.x86_64.rpm 31 MB/s | 1.6 MB 00:00 (7/37): bind-libs-lite-9.11.4-3.P2.fc27.x86_64. 33 MB/s | 1.1 MB 00:00 (8/37): python3-bind-9.11.4-3.P2.fc27.noarch.rp 13 MB/s | 140 kB 00:00 (9/37): bind-license-9.11.4-3.P2.fc27.noarch.rp 15 MB/s | 93 kB 00:00 (10/37): python3-ply-3.9-4.fc27.noarch.rpm 35 MB/s | 106 kB 00:00 (11/37): gpm-libs-1.20.7-10.fc26.x86_64.rpm 20 MB/s | 36 kB 00:00 (12/37): liblockfile-1.09-7.fc27.x86_64.rpm 10 MB/s | 28 kB 00:00 (13/37): file-5.31-12.fc27.x86_64.rpm 22 MB/s | 72 kB 00:00 (14/37): yum-utils-1.1.31-514.fc27.noarch.rpm 20 MB/s | 118 kB 00:00 (15/37): python2-kitchen-1.2.4-7.fc27.noarch.rp 23 MB/s | 121 kB 00:00 (16/37): emacs-nox-25.3-4.fc27.x86_64.rpm 38 MB/s | 2.6 MB 00:00 (17/37): yum-3.4.3-513.fc27.noarch.rpm 22 MB/s | 1.2 MB 00:00 (18/37): cpio-2.12-6.fc27.x86_64.rpm 25 MB/s | 262 kB 00:00 (19/37): pyliblzma-0.5.3-19.fc27.x86_64.rpm 19 MB/s | 54 kB 00:00 (20/37): python-urlgrabber-3.10.1-12.fc27.noarc 31 MB/s | 111 kB 00:00 (21/37): python2-iniparse-0.4-26.fc27.noarch.rp 13 MB/s | 46 kB 00:00 (22/37): python2-pygpgme-0.3-25.fc27.x86_64.rpm 29 MB/s | 92 kB 00:00 (23/37): pyxattr-0.5.3-12.fc27.x86_64.rpm 10 MB/s | 34 kB 00:00 (24/37): yum-metadata-parser-1.1.4-20.fc27.x86_ 13 MB/s | 39 kB 00:00 (25/37): python2-six-1.11.0-1.fc27.noarch.rpm 7.5 MB/s | 36 kB 00:00 (26/37): python2-pycurl-7.43.0-9.fc27.x86_64.rp 27 MB/s | 247 kB 00:00 (27/37): python2-rpm-4.14.2.1-1.fc27.x86_64.rpm 33 MB/s | 118 kB 00:00 (28/37): python2-2.7.15-4.fc27.x86_64.rpm 29 MB/s | 101 kB 00:00 (29/37): python2-pip-9.0.3-2.fc27.noarch.rpm 21 MB/s | 2.0 MB 00:00 (30/37): python2-setuptools-37.0.0-2.fc27.noarc 25 MB/s | 606 kB 00:00 (31/37): alsa-lib-1.1.5-1.fc27.x86_64.rpm 26 MB/s | 423 kB 00:00 (32/37): libjpeg-turbo-1.5.3-1.fc27.x86_64.rpm 23 MB/s | 153 kB 00:00 (33/37): jansson-2.11-1.fc27.x86_64.rpm 14 MB/s | 44 kB 00:00 (34/37): libnl3-3.4.0-1.fc27.x86_64.rpm 27 MB/s | 304 kB 00:00 (35/37): polkit-libs-0.113-16.fc27.x86_64.rpm 21 MB/s | 72 kB 00:00 (36/37): python2-libs-2.7.15-4.fc27.x86_64.rpm 35 MB/s | 6.3 MB 00:00 (37/37): emacs-common-25.3-4.fc27.x86_64.rpm 63 MB/s | 38 MB 00:00 -------------------------------------------------------------------------------- Total 87 MB/s | 58 MB 00:00 Running transaction check Transaction check succeeded. Running transaction test Transaction test succeeded. Running transaction Preparing : 1/1 Installing : python2-libs-2.7.15-4.fc27.x86_64 1/37 Running scriptlet: python2-libs-2.7.15-4.fc27.x86_64 1/37 Installing : python2-pip-9.0.3-2.fc27.noarch 2/37 Installing : python2-setuptools-37.0.0-2.fc27.noarch 3/37 Installing : python2-2.7.15-4.fc27.x86_64 4/37 Installing : bind-license-32:9.11.4-3.P2.fc27.noarch 5/37 Installing : bind-libs-lite-32:9.11.4-3.P2.fc27.x86_64 6/37 Running scriptlet: bind-libs-lite-32:9.11.4-3.P2.fc27.x86_64 6/37 Installing : jansson-2.11-1.fc27.x86_64 7/37 Running scriptlet: jansson-2.11-1.fc27.x86_64 7/37 Installing : NetworkManager-libnm-1:1.8.8-2.fc27.x86_64 8/37 Running scriptlet: NetworkManager-libnm-1:1.8.8-2.fc27.x86_64 8/37 Installing : bind-libs-32:9.11.4-3.P2.fc27.x86_64 9/37 Running scriptlet: bind-libs-32:9.11.4-3.P2.fc27.x86_64 9/37 Installing : python2-kitchen-1.2.4-7.fc27.noarch 10/37 Installing : pyliblzma-0.5.3-19.fc27.x86_64 11/37 Installing : python2-pygpgme-0.3-25.fc27.x86_64 12/37 Installing : pyxattr-0.5.3-12.fc27.x86_64 13/37 Installing : yum-metadata-parser-1.1.4-20.fc27.x86_64 14/37 Installing : python2-pycurl-7.43.0-9.fc27.x86_64 15/37 Installing : python-urlgrabber-3.10.1-12.fc27.noarch 16/37 Installing : python2-six-1.11.0-1.fc27.noarch 17/37 Installing : python2-iniparse-0.4-26.fc27.noarch 18/37 Installing : python2-rpm-4.14.2.1-1.fc27.x86_64 19/37 Installing : polkit-libs-0.113-16.fc27.x86_64 20/37 Running scriptlet: polkit-libs-0.113-16.fc27.x86_64 20/37 Installing : libnl3-3.4.0-1.fc27.x86_64 21/37 Running scriptlet: libnl3-3.4.0-1.fc27.x86_64 21/37 Installing : libjpeg-turbo-1.5.3-1.fc27.x86_64 22/37 Running scriptlet: libjpeg-turbo-1.5.3-1.fc27.x86_64 22/37 Installing : alsa-lib-1.1.5-1.fc27.x86_64 23/37 Running scriptlet: alsa-lib-1.1.5-1.fc27.x86_64 23/37 Installing : cpio-2.12-6.fc27.x86_64 24/37 Running scriptlet: cpio-2.12-6.fc27.x86_64 24/37 Installing : yum-3.4.3-513.fc27.noarch 25/37 Running scriptlet: yum-3.4.3-513.fc27.noarch 25/37 Installing : liblockfile-1.09-7.fc27.x86_64 26/37 Running scriptlet: liblockfile-1.09-7.fc27.x86_64 26/37 Installing : emacs-common-1:25.3-4.fc27.x86_64 27/37 Running scriptlet: emacs-common-1:25.3-4.fc27.x86_64 27/37 Installing : gpm-libs-1.20.7-10.fc26.x86_64 28/37 Running scriptlet: gpm-libs-1.20.7-10.fc26.x86_64 28/37 Installing : python3-ply-3.9-4.fc27.noarch 29/37 Installing : python3-bind-32:9.11.4-3.P2.fc27.noarch 30/37 Installing : libndp-1.6-4.fc27.x86_64 31/37 Running scriptlet: libndp-1.6-4.fc27.x86_64 31/37 Running scriptlet: NetworkManager-1:1.8.8-2.fc27.x86_64 32/37 Installing : NetworkManager-1:1.8.8-2.fc27.x86_64 32/37 Running scriptlet: NetworkManager-1:1.8.8-2.fc27.x86_64 32/37 /var/tmp/rpm-tmp.uHqUuv: line 1: /usr/bin/udevadm: No such file or directory /var/tmp/rpm-tmp.uHqUuv: line 2: /usr/bin/udevadm: No such file or directory Installing : bind-utils-32:9.11.4-3.P2.fc27.x86_64 33/37 Installing : emacs-nox-1:25.3-4.fc27.x86_64 34/37 Installing : yum-utils-1.1.31-514.fc27.noarch 35/37 Installing : file-5.31-12.fc27.x86_64 36/37 Installing : psmisc-23.1-2.fc27.x86_64 37/37 Running scriptlet: emacs-common-1:25.3-4.fc27.x86_64 37/37 Running scriptlet: emacs-nox-1:25.3-4.fc27.x86_64 37/37 Running scriptlet: psmisc-23.1-2.fc27.x86_64 37/37Failed to connect to bus: No such file or directory Verifying : psmisc-23.1-2.fc27.x86_64 1/37 Verifying : NetworkManager-1:1.8.8-2.fc27.x86_64 2/37 Verifying : libndp-1.6-4.fc27.x86_64 3/37 Verifying : NetworkManager-libnm-1:1.8.8-2.fc27.x86_64 4/37 Verifying : bind-utils-32:9.11.4-3.P2.fc27.x86_64 5/37 Verifying : bind-libs-32:9.11.4-3.P2.fc27.x86_64 6/37 Verifying : bind-libs-lite-32:9.11.4-3.P2.fc27.x86_64 7/37 Verifying : python3-bind-32:9.11.4-3.P2.fc27.noarch 8/37 Verifying : bind-license-32:9.11.4-3.P2.fc27.noarch 9/37 Verifying : python3-ply-3.9-4.fc27.noarch 10/37 Verifying : emacs-nox-1:25.3-4.fc27.x86_64 11/37 Verifying : gpm-libs-1.20.7-10.fc26.x86_64 12/37 Verifying : emacs-common-1:25.3-4.fc27.x86_64 13/37 Verifying : liblockfile-1.09-7.fc27.x86_64 14/37 Verifying : file-5.31-12.fc27.x86_64 15/37 Verifying : yum-utils-1.1.31-514.fc27.noarch 16/37 Verifying : python2-kitchen-1.2.4-7.fc27.noarch 17/37 Verifying : yum-3.4.3-513.fc27.noarch 18/37 Verifying : cpio-2.12-6.fc27.x86_64 19/37 Verifying : pyliblzma-0.5.3-19.fc27.x86_64 20/37 Verifying : python-urlgrabber-3.10.1-12.fc27.noarch 21/37 Verifying : python2-iniparse-0.4-26.fc27.noarch 22/37 Verifying : python2-pygpgme-0.3-25.fc27.x86_64 23/37 Verifying : pyxattr-0.5.3-12.fc27.x86_64 24/37 Verifying : yum-metadata-parser-1.1.4-20.fc27.x86_64 25/37 Verifying : python2-pycurl-7.43.0-9.fc27.x86_64 26/37 Verifying : python2-six-1.11.0-1.fc27.noarch 27/37 Verifying : python2-rpm-4.14.2.1-1.fc27.x86_64 28/37 Verifying : python2-2.7.15-4.fc27.x86_64 29/37 Verifying : python2-libs-2.7.15-4.fc27.x86_64 30/37 Verifying : python2-pip-9.0.3-2.fc27.noarch 31/37 Verifying : python2-setuptools-37.0.0-2.fc27.noarch 32/37 Verifying : alsa-lib-1.1.5-1.fc27.x86_64 33/37 Verifying : libjpeg-turbo-1.5.3-1.fc27.x86_64 34/37 Verifying : jansson-2.11-1.fc27.x86_64 35/37 Verifying : libnl3-3.4.0-1.fc27.x86_64 36/37 Verifying : polkit-libs-0.113-16.fc27.x86_64 37/37 Installed: NetworkManager.x86_64 1:1.8.8-2.fc27 bind-utils.x86_64 32:9.11.4-3.P2.fc27 emacs-nox.x86_64 1:25.3-4.fc27 file.x86_64 5.31-12.fc27 psmisc.x86_64 23.1-2.fc27 yum-utils.noarch 1.1.31-514.fc27 NetworkManager-libnm.x86_64 1:1.8.8-2.fc27 alsa-lib.x86_64 1.1.5-1.fc27 bind-libs.x86_64 32:9.11.4-3.P2.fc27 bind-libs-lite.x86_64 32:9.11.4-3.P2.fc27 bind-license.noarch 32:9.11.4-3.P2.fc27 cpio.x86_64 2.12-6.fc27 emacs-common.x86_64 1:25.3-4.fc27 gpm-libs.x86_64 1.20.7-10.fc26 jansson.x86_64 2.11-1.fc27 libjpeg-turbo.x86_64 1.5.3-1.fc27 liblockfile.x86_64 1.09-7.fc27 libndp.x86_64 1.6-4.fc27 libnl3.x86_64 3.4.0-1.fc27 polkit-libs.x86_64 0.113-16.fc27 pyliblzma.x86_64 0.5.3-19.fc27 python-urlgrabber.noarch 3.10.1-12.fc27 python2.x86_64 2.7.15-4.fc27 python2-iniparse.noarch 0.4-26.fc27 python2-kitchen.noarch 1.2.4-7.fc27 python2-libs.x86_64 2.7.15-4.fc27 python2-pip.noarch 9.0.3-2.fc27 python2-pycurl.x86_64 7.43.0-9.fc27 python2-pygpgme.x86_64 0.3-25.fc27 python2-rpm.x86_64 4.14.2.1-1.fc27 python2-setuptools.noarch 37.0.0-2.fc27 python2-six.noarch 1.11.0-1.fc27 python3-bind.noarch 32:9.11.4-3.P2.fc27 python3-ply.noarch 3.9-4.fc27 pyxattr.x86_64 0.5.3-12.fc27 yum.noarch 3.4.3-513.fc27 yum-metadata-parser.x86_64 1.1.4-20.fc27 Complete! + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 rpm -aq + return 0 + post_install 2019.01.24--f27-1-vplc01 linux64 + local lxc=2019.01.24--f27-1-vplc01 + shift + local personality=linux64 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + cp /etc/localtime /vservers/2019.01.24--f27-1-vplc01/etc/localtime + sshd_disable_password_auth 2019.01.24--f27-1-vplc01 + local lxc=2019.01.24--f27-1-vplc01 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + sed --in-place=.password -e 's,^#\?PasswordAuthentication.*,PasswordAuthentication no,' /vservers/2019.01.24--f27-1-vplc01/etc/ssh/sshd_config + '[' -n '' ']' + post_install_myplc 2019.01.24--f27-1-vplc01 linux64 + set -x + set -e + trap failure ERR INT + local lxc=2019.01.24--f27-1-vplc01 + shift + local personality=linux64 + shift ++ lxcroot 2019.01.24--f27-1-vplc01 ++ local lxc=2019.01.24--f27-1-vplc01 ++ shift ++ echo /vservers/2019.01.24--f27-1-vplc01 + local lxc_root=/vservers/2019.01.24--f27-1-vplc01 + cat + chroot /vservers/2019.01.24--f27-1-vplc01 linux64 bash -x + '[' -f /etc/sysconfig/network ']' + /sbin/chkconfig crond off error reading information on service crond: No such file or directory + /bin/cat Starting guest 2019.01.24--f27-1-vplc01 + '[' -n true ']' + echo Starting guest 2019.01.24--f27-1-vplc01 + virsh -c lxc:/// start 2019.01.24--f27-1-vplc01 error: Failed to start domain 2019.01.24--f27-1-vplc01 error: internal error: Failed to allocate free veth pair after 10 attempts lbuild-initvm.sh : Bailing out ++ failure ++ echo 'lbuild-initvm.sh : Bailing out' ++ exit 1 * 03:32:34 -- ********** 1 FAILED step plcvm_create on plctest1_vplc01 (discarded from further steps) TRACE: 1 03-32-13->21s=0:00:21.178499s status=KO step=plcvm_create plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step django_install on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000212s status=UNDEF step=django_install plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step plc_install on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000159s status=UNDEF step=plc_install plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step plc_configure on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=plc_configure plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step plc_start on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=plc_start plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step keys_fetch on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=keys_fetch plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step keys_store on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=keys_store plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step keys_clear_known_hosts on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000157s status=UNDEF step=keys_clear_known_hosts plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step plcapi_urls on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=plcapi_urls plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step speed_up_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000170s status=UNDEF step=speed_up_slices plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step initscripts on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=initscripts plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step sites on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000167s status=UNDEF step=sites plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step nodes on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=nodes plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000151s status=UNDEF step=slices plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step nodegroups on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=nodegroups plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step leases on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=leases plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step check_vsys_defaults_ignore on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000255s status=UNDEF step=check_vsys_defaults_ignore plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_kill_mine on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=qemu_kill_mine plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step nodestate_reinstall on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=nodestate_reinstall plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_local_init on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000166s status=UNDEF step=qemu_local_init plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step bootcd on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000170s status=UNDEF step=bootcd plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_local_config on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=qemu_local_config plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_clean_mine on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=qemu_clean_mine plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_export on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000153s status=UNDEF step=qemu_export plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_cleanlog on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=qemu_cleanlog plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_start on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000146s status=UNDEF step=qemu_start plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_timestamp on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=qemu_timestamp plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step qemu_nodefamily on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000151s status=UNDEF step=qemu_nodefamily plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step probe_kvm_iptables on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=probe_kvm_iptables plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ping_node on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=ping_node plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_node_debug on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000226s status=UNDEF step=ssh_node_debug plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step plcsh_stress_test on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000151s status=UNDEF step=plcsh_stress_test plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_node_boot on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=ssh_node_boot plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step node_bmlogs on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000145s status=UNDEF step=node_bmlogs plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000150s status=UNDEF step=ssh_slice plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_slice_basics on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=ssh_slice_basics plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step check_system_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=check_system_slice plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step debug_nodemanager on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000165s status=UNDEF step=debug_nodemanager plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step empty_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000152s status=UNDEF step=empty_slices plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_slice_off on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000147s status=UNDEF step=ssh_slice_off plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step slice_fs_deleted_ignore on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000149s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step fill_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000151s status=UNDEF step=fill_slices plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 SKIPPED Step ssh_slice_again on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 03-32-34->0s=0:00:00.000148s status=UNDEF step=ssh_slice_again plc=plctest1_vplc01 force=False * 03:32:34 -- ********** 1 RUNNING step gather_logs (forced after plcvm_create has failed) on plc plctest1_vplc01 -------------------- TestPlc.gather_logs : PLC's /var/log -> 03:32:34 -- + mkdir -p logs/myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01 -> 03:32:34 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc01 tar -C /var/log/ -cf - . + tar -C logs/myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc01' (ECDSA) to the list of known hosts. -> 03:32:35 -- + chmod a+r,a+x logs/myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd -------------------- TestPlc.gather_logs : PLC's /var/lib/psql/data/pg_log/ -> 03:32:35 -- + mkdir -p logs/myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01 -> 03:32:35 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc01 tar -C /var/lib/pgsql/data/pg_log/ -cf - . + tar -C logs/myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc01' (ECDSA) to the list of known hosts. -------------------- TestPlc.gather_logs : PLC's /root/sfi/ -> 03:32:36 -- + mkdir -p logs/sfi.plctest1_vplc01.2019.01.24--f27-1-vplc01 -> 03:32:36 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc01 tar -C /root/sfi/ -cf - . + tar -C logs/sfi.plctest1_vplc01.2019.01.24--f27-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc01' (ECDSA) to the list of known hosts. tar: /root/sfi/: Cannot open: No such file or directory tar: Error is not recoverable: exiting now tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -------------------- TestPlc.gather_logs : nodes's QEMU logs -> 03:32:37 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 boxtops:/vservers/2019.01.24--f27/qemu-vnode01.pl.sophia.inria.fr/log.txt logs/node.qemu.vnode01.pl.sophia.inria.fr.txt scp: /vservers/2019.01.24--f27/qemu-vnode01.pl.sophia.inria.fr/log.txt: No such file or directory -------------------- TestPlc.gather_logs : nodes's /var/log -> 03:32:37 -- + mkdir -p logs/node.var-log.vnode01.pl.sophia.inria.fr -> 03:32:37 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode01.pl.sophia.inria.fr tar -C /var/log -cf - . + tar -C logs/node.var-log.vnode01.pl.sophia.inria.fr -xf - Warning: Identity file keys/key_admin.rsa not accessible: No such file or directory. ssh: connect to host vnode01.pl.sophia.inria.fr port 22: No route to host tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -------------------- TestPlc.gather_logs : sample sliver's /var/log Traceback (most recent call last): File "TestMain.py", line 511, in run step_result = method(plc_obj) File "/root/2019.01.24--f27/TestPlc.py", line 1872, in gather_logs self.gather_slivers_var_logs() File "/root/2019.01.24--f27/TestPlc.py", line 1876, in gather_slivers_var_logs for test_sliver in self.all_sliver_objs(): File "/root/2019.01.24--f27/TestPlc.py", line 425, in all_sliver_objs result.append(self.locate_sliver_obj(nodename, slicename)) File "/root/2019.01.24--f27/TestPlc.py", line 436, in locate_sliver_obj return TestSliver(self, test_node, test_slice) File "/root/2019.01.24--f27/TestSliver.py", line 16, in __init__ self.test_ssh = self.create_test_ssh() File "/root/2019.01.24--f27/TestSliver.py", line 29, in create_test_ssh raise Exception("Cannot find the private key for slice {}".format(self.test_slice.name())) Exception: Cannot find the private key for slice inri_sl1 * 03:32:40 -- ********** 1 FAILED (exception) step gather_logs on plctest1_vplc01 (discarded from further steps) TRACE: 1 03-32-34->6s=0:00:06.235992s status=KO step=gather_logs plc=plctest1_vplc01 force=True TestMain exit code 1 run_log exit_code 1 + retcod=1 + set -e + trap failure ERR INT + echo 'retcod from run_log' 1 retcod from run_log 1 + case $retcod in + success= + IGNORED= ++ rootdir 2019.01.24--f27 ++ slice=2019.01.24--f27 ++ shift ++ echo /vservers/2019.01.24--f27 + mkdir -p /vservers/2019.01.24--f27/build/testlogs ++ rootdir 2019.01.24--f27 ++ slice=2019.01.24--f27 ++ shift ++ echo /vservers/2019.01.24--f27 + rsync --verbose --archive root@testmaster.onelab.eu:2019.01.24--f27/logs/ /vservers/2019.01.24--f27/build/testlogs receiving incremental file list ./ run -> run-03-31.txt run-03-31.txt trace -> trace-03-31.txt trace-03-31.txt myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/ myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Fri.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Mon.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Sat.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Sun.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Thu.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Tue.log myplc.pgsql-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/postgresql-Wed.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/README myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/boot.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/btmp myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/dnf.librepo.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/dnf.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/dnf.rpm.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/hawkey.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/lastlog myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/maillog myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/messages myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/myslice.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/php.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/plc_api_ratelimit.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/plcapi.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/secure myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/spooler myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/tallylog myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/wtmp myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-hostname/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-hostname/vnode01.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-hostname/vnode01.pl.sophia.inria.fr/2019-01-10-02-37 -> ../../raw/2019-01-10-02-37-vnode01.pl.sophia.inria.fr-138.96.112.101.txt myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-ip/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-ip/138.96.112.101/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-ip/138.96.112.101/2019-01-10-02-37 -> ../../raw/2019-01-10-02-37-vnode01.pl.sophia.inria.fr-138.96.112.101.txt myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-month/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-month/2019-01/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-month/2019-01/vnode01.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/per-month/2019-01/vnode01.pl.sophia.inria.fr/10-02-37 -> ../../../raw/2019-01-10-02-37-vnode01.pl.sophia.inria.fr-138.96.112.101.txt myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/raw/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/bm/raw/2019-01-10-02-37-vnode01.pl.sophia.inria.fr-138.96.112.101.txt myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/access_log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/error_log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/ssl_access_log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/ssl_error_log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/httpd/ssl_request_log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/journal/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/journal/6460bc4ae0ef4f36bf9b394ea6038edd/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/journal/6460bc4ae0ef4f36bf9b394ea6038edd/system.journal myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/mail/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/mail/statistics myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/php-fpm/ myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/php-fpm/error.log myplc.var-log.plctest1_vplc01.2019.01.24--f27-1-vplc01/php-fpm/www-error.log node.var-log.vnode01.pl.sophia.inria.fr/ sfi.plctest1_vplc01.2019.01.24--f27-1-vplc01/ sent 845 bytes received 53,698,860 bytes 35,799,803.33 bytes/sec total size is 53,682,333 speedup is 1.00 ++ rootdir 2019.01.24--f27 ++ slice=2019.01.24--f27 ++ shift ++ echo /vservers/2019.01.24--f27 + chmod -R a+r /vservers/2019.01.24--f27/build/testlogs/ ++ rootdir 2019.01.24--f27 ++ slice=2019.01.24--f27 ++ shift ++ echo /vservers/2019.01.24--f27 + webpublish_rsync /build/lxc//2019.01.24--f27/testlogs/ /vservers/2019.01.24--f27/build/testlogs/ + local remote=/build/lxc//2019.01.24--f27/testlogs/ + shift + rsync --archive --delete /vservers/2019.01.24--f27/build/testlogs/ root@build.onelab.eu:/build/lxc//2019.01.24--f27/testlogs/ ++ date + echo '============================== END lbuild-nightly.sh:run_log on Thu Jan 24 03:32:43 CET 2019' ============================== END lbuild-nightly.sh:run_log on Thu Jan 24 03:32:43 CET 2019 + '[' -z '' ']' + echo 'Tests have failed - bailing out' Tests have failed - bailing out + failure + set -x + '[' -z /build/lxc//2019.01.24--f27/log.txt ']' + webpublish mkdir -p /build/lxc//2019.01.24--f27 + ssh root@build.onelab.eu mkdir -p /build/lxc//2019.01.24--f27 + webpublish_rsync /build/lxc//2019.01.24--f27/log.txt /vservers/2019.01.24--f27/log.txt + local remote=/build/lxc//2019.01.24--f27/log.txt + shift + rsync --archive --delete /vservers/2019.01.24--f27/log.txt root@build.onelab.eu:/build/lxc//2019.01.24--f27/log.txt ******************** BEG SUMMARY Failed to analyze /vservers/2019.01.24--f27/log.txt ******************** END SUMMARY