============================== lbuild-nightly.sh: failure at Thu May 19 03:14:22 AM CEST 2022 -> 03:11:33 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2022.05.19--f35-nofed ';' virsh -c lxc:/// destroy 2022.05.19--f35-nofed-1-vplc04 Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. error: failed to get domain '2022.05.19--f35-nofed-1-vplc04' -> 03:11:33 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2022.05.19--f35-nofed ';' virsh -c lxc:/// undefine 2022.05.19--f35-nofed-1-vplc04 Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. error: failed to get domain '2022.05.19--f35-nofed-1-vplc04' -> 03:11:34 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2022.05.19--f35-nofed ';' rm -fr /vservers/2022.05.19--f35-nofed-1-vplc04 Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. * 03:11:34 -- ********** 1 SUCCESSFUL step plcvm_delete on plctest1_vplc04 TRACE: 1 03-11-32->1s=0:00:01.334918s status=OK step=plcvm_delete plc=plctest1_vplc04 force=False * 03:11:34 -- ********** 1 RUNNING step plcvm_timestamp on plc plctest1_vplc04 -> 03:11:34 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks mkdir -p /vservers/2022.05.19--f35-nofed-1-vplc04 Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. -> 03:11:34 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks echo 1652922694 '>' /vservers/2022.05.19--f35-nofed-1-vplc04/2022.05.19--f35-nofed-1-vplc04.timestamp Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. * 03:11:34 -- ********** 1 SUCCESSFUL step plcvm_timestamp on plctest1_vplc04 TRACE: 1 03-11-34->0s=0:00:00.431144s status=OK step=plcvm_timestamp plc=plctest1_vplc04 force=False * 03:11:34 -- ********** 1 RUNNING step plcvm_create on plc plctest1_vplc04 -> 03:11:34 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks rm -rf 2022.05.19--f35-nofed/build Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. -> 03:11:34 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -r build buzzcocks:2022.05.19--f35-nofed/build -> 03:11:35 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2022.05.19--f35-nofed ';' build/lbuild-initvm.sh -p linux64 -d lxc -f f35 -r http://build.onelab.eu/lxc//2022.05.19--f35-nofed/RPMS -n vplc04.pl.sophia.inria.fr 2022.05.19--f35-nofed-1-vplc04 Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. Bridge already set up - skipping create_bridge_if_needed + set -e + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local fcdistro=f35 + shift + local pldistro=lxc + shift + local personality=linux64 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 ++ package_method f35 ++ local fcdistro=f35 ++ shift ++ case $fcdistro in ++ echo dnf + pkg_method=dnf + case $pkg_method in + '[' -z '' ']' + fedora_install 2022.05.19--f35-nofed-1-vplc04 + set -x + set -e + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + local cache=/var/cache/lxc/fedora/x86_64/35 + mkdir -p /var/cache/lxc/fedora/x86_64/35 + flock --exclusive --timeout 60 200 Updating cache /var/cache/lxc/fedora/x86_64/35/rootfs ... + '[' '!' -e /var/cache/lxc/fedora/x86_64/35/rootfs ']' + echo 'Updating cache /var/cache/lxc/fedora/x86_64/35/rootfs ...' + dnf --installroot /var/cache/lxc/fedora/x86_64/35/rootfs --releasever=35 -y --nogpgcheck update Last metadata expiration check: 0:06:32 ago on Thu 19 May 2022 03:05:04 AM CEST. Dependencies resolved. Nothing to do. Complete! Update finished Filling /vservers/2022.05.19--f35-nofed-1-vplc04 from /var/cache/lxc/fedora/x86_64/35/rootfs ... + echo 'Update finished' + echo 'Filling /vservers/2022.05.19--f35-nofed-1-vplc04 from /var/cache/lxc/fedora/x86_64/35/rootfs ... ' + rsync -a /var/cache/lxc/fedora/x86_64/35/rootfs/ /vservers/2022.05.19--f35-nofed-1-vplc04/ + return 0 + return 0 ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 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 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 rpm --rebuilddb + fedora_configure 2022.05.19--f35-nofed-1-vplc04 f35 + set -x + set -e + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local fcdistro=f35 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + mkdir -p /vservers/2022.05.19--f35-nofed-1-vplc04/selinux + echo 0 + cat + cat + local dev_path=/vservers/2022.05.19--f35-nofed-1-vplc04/dev + rm -rf /vservers/2022.05.19--f35-nofed-1-vplc04/dev + mkdir -p /vservers/2022.05.19--f35-nofed-1-vplc04/dev + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/null c 1 3 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/zero c 1 5 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/random c 1 8 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/urandom c 1 9 + mkdir -m 755 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/pts + mkdir -m 1777 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/shm + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty c 5 0 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty0 c 4 0 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty1 c 4 1 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty2 c 4 2 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty3 c 4 3 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/tty4 c 4 4 + mknod -m 600 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/console c 5 1 + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/full c 1 7 + mknod -m 600 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/initctl p + mknod -m 666 /vservers/2022.05.19--f35-nofed-1-vplc04/dev/ptmx c 5 2 + fedora_configure_systemd 2022.05.19--f35-nofed-1-vplc04 + set -e + set -x + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + '[' -d /vservers/2022.05.19--f35-nofed-1-vplc04/etc/systemd ']' + ln -sf /lib/systemd/system/multi-user.target /vservers/2022.05.19--f35-nofed-1-vplc04/etc/systemd/system/default.target + touch /vservers/2022.05.19--f35-nofed-1-vplc04/etc/fstab + ln -sf /dev/null /vservers/2022.05.19--f35-nofed-1-vplc04/etc/systemd/system/udev.service + ln -sf /dev/null /vservers/2022.05.19--f35-nofed-1-vplc04/etc/systemd/system/getty@.service + rm -f /vservers/2022.05.19--f35-nofed-1-vplc04/etc/systemd/system/getty.target.wants/getty@tty1.service + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 chkconfig network on error reading information on service network: No such file or directory + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 systemctl enable NetworkManager Failed to enable unit, unit NetworkManager.service does not exist. + local guest_ifcfg=/vservers/2022.05.19--f35-nofed-1-vplc04/etc/sysconfig/network-scripts/ifcfg-eth0 ++ dirname /vservers/2022.05.19--f35-nofed-1-vplc04/etc/sysconfig/network-scripts/ifcfg-eth0 + mkdir -p /vservers/2022.05.19--f35-nofed-1-vplc04/etc/sysconfig/network-scripts + '[' -n '' ']' + write_guest_ifcfg_publicip + cat + '[' -z '' ']' + fedora_configure_yum 2022.05.19--f35-nofed-1-vplc04 f35 lxc + set -x + set -e + trap failure ERR INT + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local fcdistro=f35 + shift + local pldistro=lxc + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 rpm --rebuilddb Initializing yum.repos.d in 2022.05.19--f35-nofed-1-vplc04 + '[' -z '' ']' + echo 'Initializing yum.repos.d in 2022.05.19--f35-nofed-1-vplc04' + rm -f /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora-cisco-openh264.repo /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora.repo /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora.repo.rpmnew /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora-updates.repo /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora-updates.repo.rpmnew /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/fedora-updates-testing.repo + local repofile=/vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + yumconf_mirrors /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo build f35 '' http://mirror.onelab.eu/ + dest_yumconf=/vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + shift + builddir=build + shift + fcdistro=f35 + shift + yumexclude_line= + shift + mirrors=http://mirror.onelab.eu/ + template=build/mirroring/f35/yum.repos.d/building.repo.in + '[' '!' -f build/mirroring/f35/yum.repos.d/building.repo.in ']' + for mirror in $mirrors + yumconf_mirror /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo build/mirroring/f35/yum.repos.d/building.repo.in '' http://mirror.onelab.eu/ + dest_yumconf=/vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + shift + template=build/mirroring/f35/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/f35/yum.repos.d/building.repo.in ++ grep '^baseurl=' /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + baseurl_defs='baseurl=http://mirror.onelab.eu//fedora/releases/35/Everything/$basearch/os/ baseurl=http://mirror.onelab.eu//fedora/updates/35/Everything/$basearch/ baseurl=http://mirror.onelab.eu//fedora/updates/35/Modular/$basearch/' + '[' -z 'baseurl=http://mirror.onelab.eu//fedora/releases/35/Everything/$basearch/os/ baseurl=http://mirror.onelab.eu//fedora/updates/35/Everything/$basearch/ baseurl=http://mirror.onelab.eu//fedora/updates/35/Modular/$basearch/' ']' + for baseurl_def in $baseurl_defs ++ echo 'baseurl=http://mirror.onelab.eu//fedora/releases/35/Everything/$basearch/os/' ++ sed -e s,baseurl=,, -e 's,$basearch,x86_64,g' + baseurl=http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os/ + repomd=http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os//repodata/repomd.xml * Trying to fetch http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os//repodata/repomd.xml + echo '* Trying to fetch http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os//repodata/repomd.xml' + build_fetch http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os//repodata/repomd.xml + curl --fail --silent --max-time 60 --output /dev/null http://mirror.onelab.eu//fedora/releases/35/Everything/x86_64/os//repodata/repomd.xml + for baseurl_def in $baseurl_defs ++ echo 'baseurl=http://mirror.onelab.eu//fedora/updates/35/Everything/$basearch/' ++ sed -e s,baseurl=,, -e 's,$basearch,x86_64,g' + baseurl=http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64/ + repomd=http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64//repodata/repomd.xml * Trying to fetch http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64//repodata/repomd.xml + echo '* Trying to fetch http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64//repodata/repomd.xml' + build_fetch http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64//repodata/repomd.xml + curl --fail --silent --max-time 60 --output /dev/null http://mirror.onelab.eu//fedora/updates/35/Everything/x86_64//repodata/repomd.xml + for baseurl_def in $baseurl_defs ++ echo 'baseurl=http://mirror.onelab.eu//fedora/updates/35/Modular/$basearch/' ++ sed -e s,baseurl=,, -e 's,$basearch,x86_64,g' + baseurl=http://mirror.onelab.eu//fedora/updates/35/Modular/x86_64/ + repomd=http://mirror.onelab.eu//fedora/updates/35/Modular/x86_64//repodata/repomd.xml * Trying to fetch http://mirror.onelab.eu//fedora/updates/35/Modular/x86_64//repodata/repomd.xml + echo '* Trying to fetch http://mirror.onelab.eu//fedora/updates/35/Modular/x86_64//repodata/repomd.xml' + build_fetch http://mirror.onelab.eu//fedora/updates/35/Modular/x86_64//repodata/repomd.xml + curl --fail --silent --max-time 60 --output /dev/null http://mirror.onelab.eu//fedora/updates/35/Modular/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/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo -e 's,gpgkey=.*,gpgkey=http://mirror.onelab.eu/keys//RPM-GPG-KEY-fedora-35-primary,' + rpm --root /vservers/2022.05.19--f35-nofed-1-vplc04 --import http://mirror.onelab.eu/keys//RPM-GPG-KEY-fedora-35-primary + '[' -n http://build.onelab.eu/lxc//2022.05.19--f35-nofed/RPMS ']' + '[' '!' -d /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d ']' ++ pl_plcyumexclude f35 lxc build ++ pl_yumexclude plcyumexclude f35 lxc build ++ keyword=plcyumexclude ++ shift ++ fcdistro=f35 ++ 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 f35 -d lxc plcyumexclude build/config.lxc/yumexclude.pkgs + yumexclude=drupal + for repo in $lxc_root/etc/yum.repos.d/* + '[' -f /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo ']' + yumconf_exclude /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo exclude=drupal + repo=/vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + shift + yumexclude_line=exclude=drupal + shift + sed -i -e '/#baseurl=.*$/i\ exclude=drupal' /vservers/2022.05.19--f35-nofed-1-vplc04/etc/yum.repos.d/building.repo + cat + return 0 + '[' -d /vservers/2022.05.19--f35-nofed-1-vplc04/cgroup ']' + mkdir /vservers/2022.05.19--f35-nofed-1-vplc04/cgroup + '[' -h /vservers/2022.05.19--f35-nofed-1-vplc04/etc/resolv.conf ']' + rm -f /vservers/2022.05.19--f35-nofed-1-vplc04/etc/resolv.conf + cp /etc/resolv.conf /vservers/2022.05.19--f35-nofed-1-vplc04/etc/resolv.conf + '[' -f /vservers/2022.05.19--f35-nofed-1-vplc04/etc/hosts ']' + mkdir -p /vservers/2022.05.19--f35-nofed-1-vplc04/root/.ssh + cat /root/.ssh/id_rsa.pub + chmod 700 /vservers/2022.05.19--f35-nofed-1-vplc04/root/.ssh + chmod 600 /vservers/2022.05.19--f35-nofed-1-vplc04/root/.ssh/authorized_keys + local config_xml=/tmp/2022.05.19--f35-nofed-1-vplc04.xml + '[' -n '' ']' + write_lxc_xml_publicip 2022.05.19--f35-nofed-1-vplc04 + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + cat + virsh -c lxc:/// define /tmp/2022.05.19--f35-nofed-1-vplc04.xml Domain '2022.05.19--f35-nofed-1-vplc04' defined from /tmp/2022.05.19--f35-nofed-1-vplc04.xml + return 0 + devel_or_test_tools 2022.05.19--f35-nofed-1-vplc04 f35 lxc linux64 + set -x + set -e + trap failure ERR INT + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local fcdistro=f35 + shift + local pldistro=lxc + shift + local personality=linux64 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 ++ package_method f35 ++ local fcdistro=f35 ++ 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/2022.05.19--f35-nofed-1-vplc04 linux64 uname -i + local lxc_arch=x86_64 + '[' x86_64 = unknown ']' ++ pl_getPackages -a x86_64 f35 lxc build/config.planetlab/runtime.pkgs ++ pl_parsePkgs package -a x86_64 f35 lxc build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=package ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f35 ++ shift ++ pldistro=lxc ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f35 -d lxc package build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f35 -d lxc package build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f35 -d lxc package build/config.planetlab/runtime.pkgs + local 'packages=NetworkManager bind-utils curl emacs-nox file hostname openssh-clients psmisc' ++ pl_getGroups -a x86_64 f35 lxc build/config.planetlab/runtime.pkgs ++ pl_parsePkgs group -a x86_64 f35 lxc build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=group ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f35 ++ shift ++ pldistro=lxc ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f35 -d lxc group build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f35 -d lxc group build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f35 -d lxc group build/config.planetlab/runtime.pkgs + local groups= + case "$pkg_method" in + local has_dnf= + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 dnf --version Failed to set locale, defaulting to C.UTF-8 4.12.0 Installed: dnf-0:4.12.0-1.fc35.noarch at Thu May 12 01:05:19 2022 Built : Fedora Project at Thu May 5 21:28:32 2022 Installed: rpm-0:4.17.0-4.fc35.x86_64 at Tue Apr 26 13:51:12 2022 Built : Fedora Project at Wed Feb 2 12:39:01 2022 container has dnf - invoking with --allowerasing + has_dnf=true + '[' -n true ']' + 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 hostname openssh-clients psmisc' ']' + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 dnf -y install --allowerasing NetworkManager bind-utils curl emacs-nox file hostname openssh-clients psmisc Failed to set locale, defaulting to C.UTF-8 Fedora 35 - x86_64 2.0 MB/s | 1.7 MB 00:00 Fedora 35 - x86_64 - Updates 1.4 MB/s | 437 kB 00:00 Fedora 35 - x86_64 - Updates 10 MB/s | 3.1 MB 00:00 MyPLC 2.6 MB/s | 15 kB 00:00 Package curl-7.79.1-4.fc35.x86_64 is already installed. Package file-5.40-9.fc35.x86_64 is already installed. Package openssh-clients-8.7p1-3.fc35.x86_64 is already installed. Package psmisc-23.4-2.fc35.x86_64 is already installed. Dependencies resolved. ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: NetworkManager x86_64 1:1.32.12-2.fc35 updates 2.3 M bind-utils x86_64 32:9.16.28-1.fc35 updates 204 k emacs-nox x86_64 1:27.2-9.fc35 fedora 2.8 M hostname x86_64 3.23-5.fc35 fedora 27 k Installing dependencies: NetworkManager-libnm x86_64 1:1.32.12-2.fc35 updates 1.7 M alsa-lib x86_64 1.2.6.1-3.fc35 updates 497 k bind-libs x86_64 32:9.16.28-1.fc35 updates 1.2 M bind-license noarch 32:9.16.28-1.fc35 updates 15 k emacs-common x86_64 1:27.2-9.fc35 fedora 35 M emacs-filesystem noarch 1:27.2-9.fc35 fedora 9.0 k fstrm x86_64 0.6.1-3.fc35 fedora 28 k gpm-libs x86_64 1.20.7-28.fc35 fedora 20 k hunspell x86_64 1.7.0-11.fc35 fedora 322 k hunspell-en-GB noarch 0.20140811.1-20.fc35 fedora 225 k hunspell-en-US noarch 0.20140811.1-20.fc35 fedora 176 k hunspell-filesystem x86_64 1.7.0-11.fc35 fedora 7.8 k jansson x86_64 2.13.1-3.fc35 fedora 44 k liblockfile x86_64 1.17-1.fc35 updates 28 k libndp x86_64 1.8-2.fc35 fedora 37 k libpkgconf x86_64 1.8.0-1.fc35 fedora 36 k libuv x86_64 1:1.44.1-1.fc35 updates 150 k lmdb-libs x86_64 0.9.29-2.fc35 fedora 59 k pkgconf x86_64 1.8.0-1.fc35 fedora 41 k pkgconf-m4 noarch 1.8.0-1.fc35 fedora 14 k pkgconf-pkg-config x86_64 1.8.0-1.fc35 fedora 10 k Installing weak dependencies: enchant2 x86_64 2.3.2-1.fc35 updates 64 k hunspell-en noarch 0.20140811.1-20.fc35 fedora 180 k Transaction Summary ================================================================================ Install 27 Packages Total download size: 45 M Installed size: 129 M Downloading Packages: (1/27): emacs-filesystem-27.2-9.fc35.noarch.rpm 2.9 MB/s | 9.0 kB 00:00 (2/27): fstrm-0.6.1-3.fc35.x86_64.rpm 8.0 MB/s | 28 kB 00:00 (3/27): gpm-libs-1.20.7-28.fc35.x86_64.rpm 8.6 MB/s | 20 kB 00:00 (4/27): hostname-3.23-5.fc35.x86_64.rpm 12 MB/s | 27 kB 00:00 (5/27): hunspell-1.7.0-11.fc35.x86_64.rpm 22 MB/s | 322 kB 00:00 (6/27): hunspell-en-0.20140811.1-20.fc35.noarch 19 MB/s | 180 kB 00:00 (7/27): hunspell-en-GB-0.20140811.1-20.fc35.noa 23 MB/s | 225 kB 00:00 (8/27): hunspell-en-US-0.20140811.1-20.fc35.noa 24 MB/s | 176 kB 00:00 (9/27): hunspell-filesystem-1.7.0-11.fc35.x86_6 2.6 MB/s | 7.8 kB 00:00 (10/27): jansson-2.13.1-3.fc35.x86_64.rpm 12 MB/s | 44 kB 00:00 (11/27): emacs-nox-27.2-9.fc35.x86_64.rpm 39 MB/s | 2.8 MB 00:00 (12/27): libndp-1.8-2.fc35.x86_64.rpm 3.0 MB/s | 37 kB 00:00 (13/27): libpkgconf-1.8.0-1.fc35.x86_64.rpm 15 MB/s | 36 kB 00:00 (14/27): lmdb-libs-0.9.29-2.fc35.x86_64.rpm 12 MB/s | 59 kB 00:00 (15/27): pkgconf-1.8.0-1.fc35.x86_64.rpm 10 MB/s | 41 kB 00:00 (16/27): pkgconf-m4-1.8.0-1.fc35.noarch.rpm 4.5 MB/s | 14 kB 00:00 (17/27): pkgconf-pkg-config-1.8.0-1.fc35.x86_64 2.4 MB/s | 10 kB 00:00 (18/27): NetworkManager-libnm-1.32.12-2.fc35.x8 31 MB/s | 1.7 MB 00:00 (19/27): alsa-lib-1.2.6.1-3.fc35.x86_64.rpm 29 MB/s | 497 kB 00:00 (20/27): NetworkManager-1.32.12-2.fc35.x86_64.r 29 MB/s | 2.3 MB 00:00 (21/27): bind-license-9.16.28-1.fc35.noarch.rpm 3.3 MB/s | 15 kB 00:00 (22/27): bind-utils-9.16.28-1.fc35.x86_64.rpm 26 MB/s | 204 kB 00:00 (23/27): enchant2-2.3.2-1.fc35.x86_64.rpm 17 MB/s | 64 kB 00:00 (24/27): liblockfile-1.17-1.fc35.x86_64.rpm 9.9 MB/s | 28 kB 00:00 (25/27): bind-libs-9.16.28-1.fc35.x86_64.rpm 31 MB/s | 1.2 MB 00:00 (26/27): libuv-1.44.1-1.fc35.x86_64.rpm 6.9 MB/s | 150 kB 00:00 (27/27): emacs-common-27.2-9.fc35.x86_64.rpm 63 MB/s | 35 MB 00:00 -------------------------------------------------------------------------------- Total 81 MB/s | 45 MB 00:00 Running transaction check Transaction check succeeded. Running transaction test Transaction test succeeded. Running transaction Preparing : 1/1 Installing : hunspell-filesystem-1.7.0-11.fc35.x86_64 1/27 Installing : hunspell-en-GB-0.20140811.1-20.fc35.noarch 2/27 Installing : hunspell-en-0.20140811.1-20.fc35.noarch 3/27 Installing : hunspell-en-US-0.20140811.1-20.fc35.noarch 4/27 Installing : hunspell-1.7.0-11.fc35.x86_64 5/27 Installing : lmdb-libs-0.9.29-2.fc35.x86_64 6/27 Installing : enchant2-2.3.2-1.fc35.x86_64 7/27 Installing : libuv-1:1.44.1-1.fc35.x86_64 8/27 Installing : liblockfile-1.17-1.fc35.x86_64 9/27 Installing : bind-license-32:9.16.28-1.fc35.noarch 10/27 Installing : alsa-lib-1.2.6.1-3.fc35.x86_64 11/27 Installing : NetworkManager-libnm-1:1.32.12-2.fc35.x86_64 12/27 Installing : pkgconf-m4-1.8.0-1.fc35.noarch 13/27 Installing : libpkgconf-1.8.0-1.fc35.x86_64 14/27 Installing : pkgconf-1.8.0-1.fc35.x86_64 15/27 Installing : pkgconf-pkg-config-1.8.0-1.fc35.x86_64 16/27 Installing : libndp-1.8-2.fc35.x86_64 17/27 Installing : jansson-2.13.1-3.fc35.x86_64 18/27 Installing : gpm-libs-1.20.7-28.fc35.x86_64 19/27 Installing : fstrm-0.6.1-3.fc35.x86_64 20/27 Installing : bind-libs-32:9.16.28-1.fc35.x86_64 21/27 Installing : emacs-filesystem-1:27.2-9.fc35.noarch 22/27 Installing : emacs-common-1:27.2-9.fc35.x86_64 23/27 Installing : emacs-nox-1:27.2-9.fc35.x86_64 24/27 Installing : bind-utils-32:9.16.28-1.fc35.x86_64 25/27 Running scriptlet: NetworkManager-1:1.32.12-2.fc35.x86_64 26/27 Installing : NetworkManager-1:1.32.12-2.fc35.x86_64 26/27 Running scriptlet: NetworkManager-1:1.32.12-2.fc35.x86_64 26/27 Created symlink /etc/systemd/system/multi-user.target.wants/NetworkManager.service → /usr/lib/systemd/system/NetworkManager.service. Created symlink /etc/systemd/system/dbus-org.freedesktop.nm-dispatcher.service → /usr/lib/systemd/system/NetworkManager-dispatcher.service. Created symlink /etc/systemd/system/network-online.target.wants/NetworkManager-wait-online.service → /usr/lib/systemd/system/NetworkManager-wait-online.service. Installing : hostname-3.23-5.fc35.x86_64 27/27 Running scriptlet: hostname-3.23-5.fc35.x86_64 27/27 Running scriptlet: emacs-common-1:27.2-9.fc35.x86_64 27/27 Running scriptlet: emacs-nox-1:27.2-9.fc35.x86_64 27/27 Running scriptlet: hostname-3.23-5.fc35.x86_64 27/27 Verifying : emacs-common-1:27.2-9.fc35.x86_64 1/27 Verifying : emacs-filesystem-1:27.2-9.fc35.noarch 2/27 Verifying : emacs-nox-1:27.2-9.fc35.x86_64 3/27 Verifying : fstrm-0.6.1-3.fc35.x86_64 4/27 Verifying : gpm-libs-1.20.7-28.fc35.x86_64 5/27 Verifying : hostname-3.23-5.fc35.x86_64 6/27 Verifying : hunspell-1.7.0-11.fc35.x86_64 7/27 Verifying : hunspell-en-0.20140811.1-20.fc35.noarch 8/27 Verifying : hunspell-en-GB-0.20140811.1-20.fc35.noarch 9/27 Verifying : hunspell-en-US-0.20140811.1-20.fc35.noarch 10/27 Verifying : hunspell-filesystem-1.7.0-11.fc35.x86_64 11/27 Verifying : jansson-2.13.1-3.fc35.x86_64 12/27 Verifying : libndp-1.8-2.fc35.x86_64 13/27 Verifying : libpkgconf-1.8.0-1.fc35.x86_64 14/27 Verifying : lmdb-libs-0.9.29-2.fc35.x86_64 15/27 Verifying : pkgconf-1.8.0-1.fc35.x86_64 16/27 Verifying : pkgconf-m4-1.8.0-1.fc35.noarch 17/27 Verifying : pkgconf-pkg-config-1.8.0-1.fc35.x86_64 18/27 Verifying : NetworkManager-1:1.32.12-2.fc35.x86_64 19/27 Verifying : NetworkManager-libnm-1:1.32.12-2.fc35.x86_64 20/27 Verifying : alsa-lib-1.2.6.1-3.fc35.x86_64 21/27 Verifying : bind-libs-32:9.16.28-1.fc35.x86_64 22/27 Verifying : bind-license-32:9.16.28-1.fc35.noarch 23/27 Verifying : bind-utils-32:9.16.28-1.fc35.x86_64 24/27 Verifying : enchant2-2.3.2-1.fc35.x86_64 25/27 Verifying : liblockfile-1.17-1.fc35.x86_64 26/27 Verifying : libuv-1:1.44.1-1.fc35.x86_64 27/27 Installed: NetworkManager-1:1.32.12-2.fc35.x86_64 NetworkManager-libnm-1:1.32.12-2.fc35.x86_64 alsa-lib-1.2.6.1-3.fc35.x86_64 bind-libs-32:9.16.28-1.fc35.x86_64 bind-license-32:9.16.28-1.fc35.noarch bind-utils-32:9.16.28-1.fc35.x86_64 emacs-common-1:27.2-9.fc35.x86_64 emacs-filesystem-1:27.2-9.fc35.noarch emacs-nox-1:27.2-9.fc35.x86_64 enchant2-2.3.2-1.fc35.x86_64 fstrm-0.6.1-3.fc35.x86_64 gpm-libs-1.20.7-28.fc35.x86_64 hostname-3.23-5.fc35.x86_64 hunspell-1.7.0-11.fc35.x86_64 hunspell-en-0.20140811.1-20.fc35.noarch hunspell-en-GB-0.20140811.1-20.fc35.noarch hunspell-en-US-0.20140811.1-20.fc35.noarch hunspell-filesystem-1.7.0-11.fc35.x86_64 jansson-2.13.1-3.fc35.x86_64 liblockfile-1.17-1.fc35.x86_64 libndp-1.8-2.fc35.x86_64 libpkgconf-1.8.0-1.fc35.x86_64 libuv-1:1.44.1-1.fc35.x86_64 lmdb-libs-0.9.29-2.fc35.x86_64 pkgconf-1.8.0-1.fc35.x86_64 pkgconf-m4-1.8.0-1.fc35.noarch pkgconf-pkg-config-1.8.0-1.fc35.x86_64 Complete! + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 rpm -aq + return 0 + post_install 2022.05.19--f35-nofed-1-vplc04 linux64 + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local personality=linux64 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + cp /etc/localtime /vservers/2022.05.19--f35-nofed-1-vplc04/etc/localtime + sshd_disable_password_auth 2022.05.19--f35-nofed-1-vplc04 + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + sed --in-place=.password -e 's,^#\?PasswordAuthentication.*,PasswordAuthentication no,' /vservers/2022.05.19--f35-nofed-1-vplc04/etc/ssh/sshd_config + '[' -n '' ']' + post_install_myplc 2022.05.19--f35-nofed-1-vplc04 linux64 + set -x + set -e + trap failure ERR INT + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + local personality=linux64 + shift ++ lxcroot 2022.05.19--f35-nofed-1-vplc04 ++ local lxc=2022.05.19--f35-nofed-1-vplc04 ++ shift ++ echo /vservers/2022.05.19--f35-nofed-1-vplc04 + local lxc_root=/vservers/2022.05.19--f35-nofed-1-vplc04 + cat + chroot /vservers/2022.05.19--f35-nofed-1-vplc04 linux64 bash -x + '[' -f /etc/sysconfig/network ']' + /sbin/chkconfig crond off error reading information on service crond: No such file or directory + /bin/cat + '[' -n true ']' + echo Starting guest 2022.05.19--f35-nofed-1-vplc04 Starting guest 2022.05.19--f35-nofed-1-vplc04 + virsh -c lxc:/// start 2022.05.19--f35-nofed-1-vplc04 Domain '2022.05.19--f35-nofed-1-vplc04' started + '[' -n '' ']' + wait_for_ssh 2022.05.19--f35-nofed-1-vplc04 138.96.112.74 + set -x + set -e + local lxc=2022.05.19--f35-nofed-1-vplc04 + shift + '[' -n 138.96.112.74 ']' + guest_ip=138.96.112.74 + shift + local success= ++ date +%s + local current_time=1652922716 + local stop_time=1652922836 + local counter=1 + '[' 1652922716 -lt 1652922836 ']' + echo '1-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' 1-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=2 + sleep 10 ++ date +%s + current_time=1652922729 + '[' 1652922729 -lt 1652922836 ']' 2-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '2-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=3 + sleep 10 ++ date +%s + current_time=1652922742 + '[' 1652922742 -lt 1652922836 ']' 3-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '3-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=4 + sleep 10 ++ date +%s + current_time=1652922756 + '[' 1652922756 -lt 1652922836 ']' 4-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '4-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=5 + sleep 10 ++ date +%s + current_time=1652922769 + '[' 1652922769 -lt 1652922836 ']' 5-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '5-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=6 + sleep 10 ++ date +%s + current_time=1652922782 + '[' 1652922782 -lt 1652922836 ']' 6-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '6-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=7 + sleep 10 ++ date +%s 7-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + current_time=1652922795 + '[' 1652922795 -lt 1652922836 ']' + echo '7-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=8 + sleep 10 ++ date +%s + current_time=1652922808 + '[' 1652922808 -lt 1652922836 ']' 8-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '8-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=9 + sleep 10 ++ date +%s + current_time=1652922821 + '[' 1652922821 -lt 1652922836 ']' 9-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '9-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=10 + sleep 10 ++ date +%s + current_time=1652922834 + '[' 1652922834 -lt 1652922836 ']' 10-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ... + echo '10-th attempt to reach sshd in container 2022.05.19--f35-nofed-1-vplc04 ...' + '[' -z 138.96.112.74 ']' + '[' -n 138.96.112.74 ']' + ssh -o 'StrictHostKeyChecking no' 138.96.112.74 'uname -i' ssh: connect to host 138.96.112.74 port 22: No route to host + : + virsh -c lxc:/// lxc-enter-namespace 2022.05.19--f35-nofed-1-vplc04 /bin/bash -c 'chown root:ssh_keys /etc/ssh/*_key' error: Requested operation is not valid: domain is not running + : + counter=11 + sleep 10 ++ date +%s + current_time=1652922847 + '[' 1652922847 -lt 1652922836 ']' SSHD in container 2022.05.19--f35-nofed-1-vplc04 could not be reached (guest_ip=138.96.112.74) + '[' -z ']' + echo 'SSHD in container 2022.05.19--f35-nofed-1-vplc04 could not be reached (guest_ip=138.96.112.74)' + exit 1 * 03:14:07 -- ********** 1 FAILED step plcvm_create on plctest1_vplc04 (discarded from further steps) TRACE: 1 03-11-34->153s=0:02:33.183047s status=KO step=plcvm_create plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step django_install on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000086s status=UNDEF step=django_install plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step plc_install on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000059s status=UNDEF step=plc_install plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step plc_configure on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000065s status=UNDEF step=plc_configure plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step plc_start on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000053s status=UNDEF step=plc_start plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step keys_fetch on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000052s status=UNDEF step=keys_fetch plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step keys_store on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000052s status=UNDEF step=keys_store plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step keys_clear_known_hosts on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000052s status=UNDEF step=keys_clear_known_hosts plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step plcapi_urls on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000051s status=UNDEF step=plcapi_urls plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step speed_up_slices on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000051s status=UNDEF step=speed_up_slices plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step initscripts on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000052s status=UNDEF step=initscripts plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step sites on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000069s status=UNDEF step=sites plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step nodes on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000055s status=UNDEF step=nodes plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step slices on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000052s status=UNDEF step=slices plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step nodegroups on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000051s status=UNDEF step=nodegroups plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 SKIPPED Step leases on plctest1_vplc04 (has failed plcvm_create) TRACE: 1 03-14-07->0s=0:00:00.000051s status=UNDEF step=leases plc=plctest1_vplc04 force=False * 03:14:07 -- ********** 1 RUNNING step gather_logs (forced after plcvm_create has failed) on plc plctest1_vplc04 -------------------- TestPlc.gather_logs : PLC's /var/log -> 03:14:07 -- + mkdir -p logs/myplc.var-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -> 03:14:07 -- + 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 vplc04 tar -C /var/log/ -cf - . + tar -C logs/myplc.var-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -xf - Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. ssh: connect to host vplc04 port 22: No route to host tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -> 03:14:11 -- + chmod a+r,a+x logs/myplc.var-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04/httpd chmod: cannot access 'logs/myplc.var-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04/httpd': No such file or directory -------------------- TestPlc.gather_logs : PLC's /var/lib/psql/data/pg_log/ -> 03:14:11 -- + mkdir -p logs/myplc.pgsql-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -> 03:14:11 -- + 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 vplc04 tar -C /var/lib/pgsql/data/pg_log/ -cf - . + tar -C logs/myplc.pgsql-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -xf - Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. ssh: connect to host vplc04 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 : PLC's /root/sfi/ -> 03:14:14 -- + mkdir -p logs/sfi.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -> 03:14:14 -- + 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 vplc04 tar -C /root/sfi/ -cf - . + tar -C logs/sfi.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04 -xf - Warning: Permanently added 'buzzcocks' (ED25519) to the list of known hosts. ssh: connect to host vplc04 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 : nodes's QEMU logs -> 03:14:17 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 boxtops:/vservers/2022.05.19--f35-nofed/qemu-vnode07.pl.sophia.inria.fr/log.txt logs/node.qemu.vnode07.pl.sophia.inria.fr.txt @@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ @ WARNING: REMOTE HOST IDENTIFICATION HAS CHANGED! @ @@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ IT IS POSSIBLE THAT SOMEONE IS DOING SOMETHING NASTY! Someone could be eavesdropping on you right now (man-in-the-middle attack)! It is also possible that a host key has just been changed. The fingerprint for the ECDSA key sent by the remote host is SHA256:MFS2iT129uqHuPZazRGgM0WTUdfcrdWHIp+EADpr6HI. Please contact your system administrator. Add correct host key in /root/.ssh/known_hosts to get rid of this message. Offending ECDSA key in /root/.ssh/known_hosts:2 Password authentication is disabled to avoid man-in-the-middle attacks. Keyboard-interactive authentication is disabled to avoid man-in-the-middle attacks. UpdateHostkeys is disabled because the host key is not trusted. scp: /vservers/2022.05.19--f35-nofed/qemu-vnode07.pl.sophia.inria.fr/log.txt: No such file or directory -------------------- TestPlc.gather_logs : nodes's /var/log -> 03:14:17 -- + mkdir -p logs/node.var-log.vnode07.pl.sophia.inria.fr -> 03:14:17 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode07.pl.sophia.inria.fr tar -C /var/log -cf - . + tar -C logs/node.var-log.vnode07.pl.sophia.inria.fr -xf - Warning: Identity file keys/key_admin.rsa not accessible: No such file or directory. ssh: connect to host vnode07.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 "/root/2022.05.19--f35-nofed/TestMain.py", line 511, in run step_result = method(plc_obj) File "/root/2022.05.19--f35-nofed/TestPlc.py", line 1928, in gather_logs self.gather_slivers_var_logs() File "/root/2022.05.19--f35-nofed/TestPlc.py", line 1932, in gather_slivers_var_logs for test_sliver in self.all_sliver_objs(): File "/root/2022.05.19--f35-nofed/TestPlc.py", line 425, in all_sliver_objs result.append(self.locate_sliver_obj(nodename, slicename)) File "/root/2022.05.19--f35-nofed/TestPlc.py", line 436, in locate_sliver_obj return TestSliver(self, test_node, test_slice) File "/root/2022.05.19--f35-nofed/TestSliver.py", line 16, in __init__ self.test_ssh = self.create_test_ssh() File "/root/2022.05.19--f35-nofed/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:14:20 -- ********** 1 FAILED (exception) step gather_logs on plctest1_vplc04 (discarded from further steps) TRACE: 1 03-14-07->13s=0:00:13.083599s status=KO step=gather_logs plc=plctest1_vplc04 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 2022.05.19--f35-nofed ++ slice=2022.05.19--f35-nofed ++ shift ++ echo /vservers/2022.05.19--f35-nofed + mkdir -p /vservers/2022.05.19--f35-nofed/build/testlogs ++ rootdir 2022.05.19--f35-nofed ++ slice=2022.05.19--f35-nofed ++ shift ++ echo /vservers/2022.05.19--f35-nofed + rsync --verbose --archive root@testmaster.onelab.eu:2022.05.19--f35-nofed/logs/ /vservers/2022.05.19--f35-nofed/build/testlogs receiving incremental file list ./ run -> run-03-11.txt run-03-11.txt trace -> trace-03-11.txt trace-03-11.txt myplc.pgsql-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04/ myplc.var-log.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04/ node.var-log.vnode07.pl.sophia.inria.fr/ sfi.plctest1_vplc04.2022.05.19--f35-nofed-1-vplc04/ sent 91 bytes received 72,972 bytes 146,126.00 bytes/sec total size is 72,377 speedup is 0.99 ++ rootdir 2022.05.19--f35-nofed ++ slice=2022.05.19--f35-nofed ++ shift ++ echo /vservers/2022.05.19--f35-nofed + chmod -R a+r /vservers/2022.05.19--f35-nofed/build/testlogs/ ++ rootdir 2022.05.19--f35-nofed ++ slice=2022.05.19--f35-nofed ++ shift ++ echo /vservers/2022.05.19--f35-nofed + webpublish_rsync /build/lxc//2022.05.19--f35-nofed/testlogs/ /vservers/2022.05.19--f35-nofed/build/testlogs/ + local remote=/build/lxc//2022.05.19--f35-nofed/testlogs/ + shift + rsync --archive --delete /vservers/2022.05.19--f35-nofed/build/testlogs/ root@build.onelab.eu:/build/lxc//2022.05.19--f35-nofed/testlogs/ ++ date + echo '============================== END lbuild-nightly.sh:run_log on Thu May 19 03:14:21 AM CEST 2022' ============================== END lbuild-nightly.sh:run_log on Thu May 19 03:14:21 AM CEST 2022 + '[' -z '' ']' + echo 'Tests have failed - bailing out' Tests have failed - bailing out + failure + set -x + '[' -z /build/lxc//2022.05.19--f35-nofed/log.txt ']' + webpublish mkdir -p /build/lxc//2022.05.19--f35-nofed + ssh root@build.onelab.eu mkdir -p /build/lxc//2022.05.19--f35-nofed + webpublish_rsync /build/lxc//2022.05.19--f35-nofed/log.txt /vservers/2022.05.19--f35-nofed/log.txt + local remote=/build/lxc//2022.05.19--f35-nofed/log.txt + shift + rsync --archive --delete /vservers/2022.05.19--f35-nofed/log.txt root@build.onelab.eu:/build/lxc//2022.05.19--f35-nofed/log.txt ******************** BEG SUMMARY Failed to analyze /vservers/2022.05.19--f35-nofed/log.txt ******************** END SUMMARY