* 11:18:14 -- Checking if build provides SFA package... -> 11:18:14 -- + curl --silent http://build.onelab.eu/onelab//2016.01.18--f14-n3/RPMS/x86_64/ + grep -q sfa- * 11:18:14 -- build does provide SFA Sensing local substrate... xp qn WARNING, duplicate qemu vnode10.pl.sophia.inria.fr running on boxtops WARNING, duplicate qemu vnode17.pl.sophia.inria.fr running on boxtops Done =============== Active plc VMs on buzzcocks {lxc-f23 257852.96484375 Mb} [max=20,free=-5] (0 users, load: 1.94, 1.96, 2.44) == vplc01 == [=2016.01.10--f23-n1-1-vplc01] (pid=64367) @ 2016-01-10:21-47 == vplc02 == [=2016.01.11--f23-n1-1-vplc02] (pid=83635) @ 2016-01-11:09-41 == vplc03 == [=2016.01.11--f22-n1-1-vplc03] (pid=47468) @ 2016-01-11:10-20 == vplc04 == [=2016.01.12--f23-1-vplc04] (pid=93742) @ 2016-01-12:01-31 == vplc05 == [=2016.01.12--f22-1-vplc05] (pid=3415) @ 2016-01-12:01-42 == vplc06 == [=2016.01.12--f23-sfatag-1-vplc06] (pid=30049) @ 2016-01-12:01-51 == vplc07 == [=2016.01.13--f23-1-vplc07] (pid=129459) @ 2016-01-13:01-31 == vplc08 == [=2016.01.13--f22-1-vplc08] (pid=36065) @ 2016-01-13:01-42 == vplc09 == [=2016.01.13--f23-sfatag-1-vplc09] (pid=74289) @ 2016-01-13:01-52 == vplc10 == [=2016.01.14--f23-1-vplc10] (pid=100415) @ 2016-01-14:01-32 == vplc11 == [=2016.01.14--f22-1-vplc11] (pid=28867) @ 2016-01-14:01-43 == vplc12 == [=2016.01.14--f23-sfatag-1-vplc12] (pid=57910) @ 2016-01-14:01-52 == vplc13 == [=2016.01.15--f23-1-vplc13] (pid=10732) @ 2016-01-15:01-31 == vplc14 == [=2016.01.15--f22-1-vplc14] (pid=85627) @ 2016-01-15:01-42 == vplc15 == [=2016.01.15--f23-sfatag-1-vplc15] (pid=123950) @ 2016-01-15:01-51 == vplc16 == [=2016.01.16--f23-1-vplc16] (pid=34086) @ 2016-01-16:01-31 == vplc17 == [=2016.01.16--f22-1-vplc17] (pid=108778) @ 2016-01-16:01-42 == vplc18 == [=2016.01.16--f23-sfatag-1-vplc18] (pid=11107) @ 2016-01-16:01-51 == vplc19 == [=2016.01.17--f23-1-vplc19] (pid=134483) @ 2016-01-17:01-31 == vplc20 == [=2016.01.17--f22-1-vplc20] (pid=61190) @ 2016-01-17:01-42 == vplc21 == [=2016.01.17--f23-sfatag-1-vplc21] (pid=113735) @ 2016-01-17:01-51 == vplc22 == [=2016.01.18--f23-1-vplc22] (pid=104932) @ 2016-01-18:01-32 == vplc23 == [=2016.01.18--f22-1-vplc23] (pid=37389) @ 2016-01-18:01-43 == vplc24 == [=2016.01.18--f23-sfatag-1-vplc24] (pid=92694) @ 2016-01-18:01-52 == vplc25 == [=2016.01.18--f23-n1-1-vplc25] (pid=14301) @ 2016-01-18:10-44 =============== Qemus on boxtops {qemu-f23 257852.96875 Mb} [max=64,free=44] (0 users, load: 4.42, 4.47, 4.57) kvm_intel OK == vnode04 == [=2016.01.12--f23] (pid=18179) @ 2016-01-12:01-34 == vnode05 == [=2016.01.12--f22] (pid=21856) @ 2016-01-12:01-44 == vnode06 == [=2016.01.12--f23-sfatag] (pid=25428) @ 2016-01-12:01-54 == vnode07 == [=2016.01.13--f23] (pid=86167) @ 2016-01-13:01-34 == vnode08 == [=2016.01.13--f22] (pid=89918) @ 2016-01-13:01-44 == vnode09 == [=2016.01.13--f23-sfatag] (pid=93642) @ 2016-01-13:01-54 == vnode10 == [=2016.01.14--f23] (pid=10043) @ 2016-01-14:01-34 == vnode11 == [=2016.01.14--f22] (pid=13665) @ 2016-01-14:01-45 == vnode12 == [=2016.01.14--f23-sfatag] (pid=17284) @ 2016-01-14:01-54 == vnode13 == [=2016.01.15--f22] (pid=88767) @ 2016-01-15:01-45 == vnode14 == [=2016.01.15--f23-sfatag] (pid=92442) @ 2016-01-15:01-54 == vnode15 == [=2016.01.16--f23] (pid=13743) @ 2016-01-16:01-34 == vnode16 == [=2016.01.16--f22] (pid=17586) @ 2016-01-16:01-44 == vnode17 == [=2016.01.16--f23-sfatag] (pid=21323) @ 2016-01-16:01-54 == vnode18 == [=2016.01.17--f22] (pid=99318) @ 2016-01-17:01-45 == vnode19 == [=2016.01.17--f23-sfatag] (pid=103093) @ 2016-01-17:01-54 == vnode20 == [=2016.01.18--f23] (pid=34341) @ 2016-01-18:01-35 == vnode01 == [=2016.01.18--f22] (pid=38321) @ 2016-01-18:01-45 == vnode02 == [=2016.01.18--f23-sfatag] (pid=42202) @ 2016-01-18:01-54 == vnode03 == [=2016.01.18--f23-n1] (pid=129649) @ 2016-01-18:10-46 Sensing IP pool for vplcs * * * * * * * * * * * * * * * * * * * * * * * * * Done After having loaded 'starting': IP pool for vplcs * * * * * * * * * * * * * * * * * * * * * * * * * =============== lxc container stopping 2016.01.10--f23-n1-1-vplc01 on buzzcocks setlocale: No such file or directory error: command 'dominfo' requires option setlocale: No such file or directory error: command 'destroy' requires option WARNING: failed to run /root/lxc-driver.sh -c stop_lxc -n 2016.01.10--f23-n1-1-vplc01 on buzzcocks WARNING: host vplc01 not found in IP pool for vplcs * 11:18:18 -- PROVISION plc plctest1 in box buzzcocks at IP vplc01 as 2016.01.18--f14-n3-1-vplc01 Sensing IP pool for vnodes * * * * * * * * * * * * * * * * * * * * Done After having loaded 'starting': IP pool for vnodes * * * * * * * * * * * * * * * * * * * * =============== Killing qemu vnode04.pl.sophia.inria.fr with pid=18179 on box boxtops * 11:18:18 -- PROVISION node node1 in box boxtops at IP vnode04 with MAC 02:34:56:00:00:04 =============== Active plc VMs on buzzcocks {lxc-f23 257852.96484375 Mb} [max=20,free=-5] (0 users, load: 1.94, 1.96, 2.44) == vplc02 == [=2016.01.11--f23-n1-1-vplc02] (pid=83635) @ 2016-01-11:09-41 == vplc03 == [=2016.01.11--f22-n1-1-vplc03] (pid=47468) @ 2016-01-11:10-20 == vplc04 == [=2016.01.12--f23-1-vplc04] (pid=93742) @ 2016-01-12:01-31 == vplc05 == [=2016.01.12--f22-1-vplc05] (pid=3415) @ 2016-01-12:01-42 == vplc06 == [=2016.01.12--f23-sfatag-1-vplc06] (pid=30049) @ 2016-01-12:01-51 == vplc07 == [=2016.01.13--f23-1-vplc07] (pid=129459) @ 2016-01-13:01-31 == vplc08 == [=2016.01.13--f22-1-vplc08] (pid=36065) @ 2016-01-13:01-42 == vplc09 == [=2016.01.13--f23-sfatag-1-vplc09] (pid=74289) @ 2016-01-13:01-52 == vplc10 == [=2016.01.14--f23-1-vplc10] (pid=100415) @ 2016-01-14:01-32 == vplc11 == [=2016.01.14--f22-1-vplc11] (pid=28867) @ 2016-01-14:01-43 == vplc12 == [=2016.01.14--f23-sfatag-1-vplc12] (pid=57910) @ 2016-01-14:01-52 == vplc13 == [=2016.01.15--f23-1-vplc13] (pid=10732) @ 2016-01-15:01-31 == vplc14 == [=2016.01.15--f22-1-vplc14] (pid=85627) @ 2016-01-15:01-42 == vplc15 == [=2016.01.15--f23-sfatag-1-vplc15] (pid=123950) @ 2016-01-15:01-51 == vplc16 == [=2016.01.16--f23-1-vplc16] (pid=34086) @ 2016-01-16:01-31 == vplc17 == [=2016.01.16--f22-1-vplc17] (pid=108778) @ 2016-01-16:01-42 == vplc18 == [=2016.01.16--f23-sfatag-1-vplc18] (pid=11107) @ 2016-01-16:01-51 == vplc19 == [=2016.01.17--f23-1-vplc19] (pid=134483) @ 2016-01-17:01-31 == vplc20 == [=2016.01.17--f22-1-vplc20] (pid=61190) @ 2016-01-17:01-42 == vplc21 == [=2016.01.17--f23-sfatag-1-vplc21] (pid=113735) @ 2016-01-17:01-51 == vplc22 == [=2016.01.18--f23-1-vplc22] (pid=104932) @ 2016-01-18:01-32 == vplc23 == [=2016.01.18--f22-1-vplc23] (pid=37389) @ 2016-01-18:01-43 == vplc24 == [=2016.01.18--f23-sfatag-1-vplc24] (pid=92694) @ 2016-01-18:01-52 == vplc25 == [=2016.01.18--f23-n1-1-vplc25] (pid=14301) @ 2016-01-18:10-44 == dummy_plctest1 == [=dummy_plctest1] (pid=0) @ 2016-01-18:11-18 =============== Qemus on boxtops {qemu-f23 257852.96875 Mb} [max=64,free=44] (0 users, load: 4.42, 4.47, 4.57) kvm_intel OK == vnode05 == [=2016.01.12--f22] (pid=21856) @ 2016-01-12:01-44 == vnode06 == [=2016.01.12--f23-sfatag] (pid=25428) @ 2016-01-12:01-54 == vnode07 == [=2016.01.13--f23] (pid=86167) @ 2016-01-13:01-34 == vnode08 == [=2016.01.13--f22] (pid=89918) @ 2016-01-13:01-44 == vnode09 == [=2016.01.13--f23-sfatag] (pid=93642) @ 2016-01-13:01-54 == vnode10 == [=2016.01.14--f23] (pid=10043) @ 2016-01-14:01-34 == vnode11 == [=2016.01.14--f22] (pid=13665) @ 2016-01-14:01-45 == vnode12 == [=2016.01.14--f23-sfatag] (pid=17284) @ 2016-01-14:01-54 == vnode13 == [=2016.01.15--f22] (pid=88767) @ 2016-01-15:01-45 == vnode14 == [=2016.01.15--f23-sfatag] (pid=92442) @ 2016-01-15:01-54 == vnode15 == [=2016.01.16--f23] (pid=13743) @ 2016-01-16:01-34 == vnode16 == [=2016.01.16--f22] (pid=17586) @ 2016-01-16:01-44 == vnode17 == [=2016.01.16--f23-sfatag] (pid=21323) @ 2016-01-16:01-54 == vnode18 == [=2016.01.17--f22] (pid=99318) @ 2016-01-17:01-45 == vnode19 == [=2016.01.17--f23-sfatag] (pid=103093) @ 2016-01-17:01-54 == vnode20 == [=2016.01.18--f23] (pid=34341) @ 2016-01-18:01-35 == vnode01 == [=2016.01.18--f22] (pid=38321) @ 2016-01-18:01-45 == vnode02 == [=2016.01.18--f23-sfatag] (pid=42202) @ 2016-01-18:01-54 == vnode03 == [=2016.01.18--f23-n1] (pid=129649) @ 2016-01-18:10-46 == dummy_vnode04 == [=None] not (yet?) running @ 2016-01-18:11-18 * 11:18:18 -- ********** 1 RUNNING step show on plc plctest1_vplc01 + ======== site main site for plc number 1 + nodes : vnode04.pl.sophia.inria.fr + node=node1 host_box=boxtops hostname= vnode04.pl.sophia.inria.fr ip= 138.96.112.104 * 11:18:18 -- ********** 1 SUCCESSFUL step show on plctest1_vplc01 TRACE: 1 11-18-18->0s=0:00:00.000258s status=OK step=show plc=plctest1_vplc01 force=False * 11:18:18 -- ********** 1 RUNNING step plcvm_delete on plc plctest1_vplc01 -> 11:18:18 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks mkdir -p 2016.01.18--f14-n3 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 11:18:19 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2016.01.18--f14-n3 ';' rm -f /vservers/2016.01.18--f14-n3-1-vplc01/2016.01.18--f14-n3-1-vplc01.timestamp Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 11:18:19 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2016.01.18--f14-n3 ';' virsh -c lxc:// destroy 2016.01.18--f14-n3-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. setlocale: No such file or directory error: failed to get domain '2016.01.18--f14-n3-1-vplc01' error: Domain not found: No domain with matching name '2016.01.18--f14-n3-1-vplc01' -> 11:18:19 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2016.01.18--f14-n3 ';' virsh -c lxc:// undefine 2016.01.18--f14-n3-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. setlocale: No such file or directory error: failed to get domain '2016.01.18--f14-n3-1-vplc01' error: Domain not found: No domain with matching name '2016.01.18--f14-n3-1-vplc01' -> 11:18:20 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2016.01.18--f14-n3 ';' rm -fr /vservers/2016.01.18--f14-n3-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. * 11:18:20 -- ********** 1 SUCCESSFUL step plcvm_delete on plctest1_vplc01 TRACE: 1 11-18-18->1s=0:00:01.443581s status=OK step=plcvm_delete plc=plctest1_vplc01 force=False * 11:18:20 -- ********** 1 RUNNING step plcvm_timestamp on plc plctest1_vplc01 -> 11:18:20 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks mkdir -p /vservers/2016.01.18--f14-n3-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 11:18:20 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks echo 1453112300 '>' /vservers/2016.01.18--f14-n3-1-vplc01/2016.01.18--f14-n3-1-vplc01.timestamp Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. * 11:18:20 -- ********** 1 SUCCESSFUL step plcvm_timestamp on plctest1_vplc01 TRACE: 1 11-18-20->0s=0:00:00.535382s status=OK step=plcvm_timestamp plc=plctest1_vplc01 force=False * 11:18:20 -- ********** 1 RUNNING step plcvm_create on plc plctest1_vplc01 -> 11:18:20 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks rm -rf 2016.01.18--f14-n3/build Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. -> 11:18:21 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -r build buzzcocks:2016.01.18--f14-n3/build -> 11:18:21 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks cd 2016.01.18--f14-n3 ';' build/lbuild-initvm.sh -p linux64 -d onelab -f f14 -r http://build.onelab.eu/onelab//2016.01.18--f14-n3/RPMS -n vplc01.pl.sophia.inria.fr 2016.01.18--f14-n3-1-vplc01 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Bridge already set up - skipping create_bridge_if_needed + set -e + lxc=2016.01.18--f14-n3-1-vplc01 + shift + fcdistro=f14 + shift + pldistro=onelab + shift + personality=linux64 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 ++ package_method f14 ++ fcdistro=f14 ++ shift ++ case $fcdistro in ++ echo yum + pkg_method=yum + case $pkg_method in + '[' -z '' ']' + fedora_install 2016.01.18--f14-n3-1-vplc01 + set -x + set -e + lxc=2016.01.18--f14-n3-1-vplc01 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + cache=/var/cache/lxc/fedora/x86_64/14 + mkdir -p /var/cache/lxc/fedora/x86_64/14 + flock --exclusive --timeout 60 200 Updating cache /var/cache/lxc/fedora/x86_64/14/rootfs ... + '[' '!' -e /var/cache/lxc/fedora/x86_64/14/rootfs ']' + echo 'Updating cache /var/cache/lxc/fedora/x86_64/14/rootfs ...' + yum --installroot /var/cache/lxc/fedora/x86_64/14/rootfs --releasever 14 -y --nogpgcheck update Yum command has been deprecated, redirecting to '/usr/bin/dnf --installroot /var/cache/lxc/fedora/x86_64/14/rootfs --releasever 14 -y --nogpgcheck update'. See 'man dnf' and 'man yum2dnf' for more information. To transfer transaction metadata from yum to DNF, run: 'dnf install python-dnf-plugins-extras-migrate && dnf-2 migrate' Last metadata expiration check performed 0:58:04 ago on Mon Jan 18 10:20:17 2016. Dependencies resolved. Nothing to do. Complete! Failed to set locale, defaulting to C Update finished + echo 'Update finished' Filling /vservers/2016.01.18--f14-n3-1-vplc01 from /var/cache/lxc/fedora/x86_64/14/rootfs ... + echo 'Filling /vservers/2016.01.18--f14-n3-1-vplc01 from /var/cache/lxc/fedora/x86_64/14/rootfs ... ' + rsync -a /var/cache/lxc/fedora/x86_64/14/rootfs/ /vservers/2016.01.18--f14-n3-1-vplc01/ + return 0 + return 0 ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + chroot /vservers/2016.01.18--f14-n3-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 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 rpm --rebuilddb rpmdb: __db_meta_setup: /var/lib/rpm/Name: unexpected file type or format error: cannot open Name index using db3 - Invalid argument (22) + fedora_configure 2016.01.18--f14-n3-1-vplc01 + set -x + set -e + lxc=2016.01.18--f14-n3-1-vplc01 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + mkdir -p /vservers/2016.01.18--f14-n3-1-vplc01/selinux + echo 0 + case "$fcdistro" in + cat + cat + echo + dev_path=/vservers/2016.01.18--f14-n3-1-vplc01/dev + rm -rf /vservers/2016.01.18--f14-n3-1-vplc01/dev + mkdir -p /vservers/2016.01.18--f14-n3-1-vplc01/dev + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/null c 1 3 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/zero c 1 5 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/random c 1 8 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/urandom c 1 9 + mkdir -m 755 /vservers/2016.01.18--f14-n3-1-vplc01/dev/pts + mkdir -m 1777 /vservers/2016.01.18--f14-n3-1-vplc01/dev/shm + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty c 5 0 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty0 c 4 0 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty1 c 4 1 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty2 c 4 2 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty3 c 4 3 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/tty4 c 4 4 + mknod -m 600 /vservers/2016.01.18--f14-n3-1-vplc01/dev/console c 5 1 + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/full c 1 7 + mknod -m 600 /vservers/2016.01.18--f14-n3-1-vplc01/dev/initctl p + mknod -m 666 /vservers/2016.01.18--f14-n3-1-vplc01/dev/ptmx c 5 2 ++ cut -df -f2 ++ echo f14 + '[' 14 -le 14 ']' + fedora_configure_init 2016.01.18--f14-n3-1-vplc01 + set -e + set -x + lxc=2016.01.18--f14-n3-1-vplc01 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + sed -i 's|.sbin.start_udev||' /vservers/2016.01.18--f14-n3-1-vplc01/etc/rc.sysinit + sed -i 's|.sbin.start_udev||' /vservers/2016.01.18--f14-n3-1-vplc01/etc/rc.d/rc.sysinit + sed -i 's/^.*dev.pts.*$/#\0/' /vservers/2016.01.18--f14-n3-1-vplc01/etc/rc.sysinit + sed -i 's/^.*dev.pts.*$/#\0/' /vservers/2016.01.18--f14-n3-1-vplc01/etc/rc.d/rc.sysinit + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 chkconfig udev-post off + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 chkconfig network on + guest_ifcfg=/vservers/2016.01.18--f14-n3-1-vplc01/etc/sysconfig/network-scripts/ifcfg-eth0 + '[' -n '' ']' + write_guest_ifcfg_publicip + cat + '[' -z '' ']' + fedora_configure_yum 2016.01.18--f14-n3-1-vplc01 f14 onelab + set -x + set -e + trap failure ERR INT + lxc=2016.01.18--f14-n3-1-vplc01 + shift + fcdistro=f14 + shift + pldistro=onelab + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 rpm --rebuilddb Initializing yum.repos.d in 2016.01.18--f14-n3-1-vplc01 + echo 'Initializing yum.repos.d in 2016.01.18--f14-n3-1-vplc01' + rm -f /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora.repo /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora.repo.rpmnew /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora-updates.repo /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora-updates.repo.rpmnew /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora-updates-testing.repo /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/fedora-updates-testing.repo.rpmnew + cat + '[' -n http://build.onelab.eu/onelab//2016.01.18--f14-n3/RPMS ']' + '[' '!' -d /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d ']' ++ pl_plcyumexclude f14 onelab build ++ pl_yumexclude plcyumexclude f14 onelab build ++ keyword=plcyumexclude ++ shift ++ fcdistro=f14 ++ shift ++ pldistro=onelab ++ shift ++ builddir=build ++ shift +++ pl_locateDistroFile build onelab yumexclude.pkgs +++ builddir=build +++ shift +++ pldistro=onelab +++ shift +++ pkgsfile=yumexclude.pkgs +++ shift +++ pkgspath= +++ configdir=build/config.onelab +++ '[' '!' -d build/config.onelab ']' +++ paths='build/config.onelab/yumexclude.pkgs build/config.planetlab/yumexclude.pkgs' +++ for path in '$paths' +++ '[' -f build/config.onelab/yumexclude.pkgs ']' +++ for path in '$paths' +++ '[' -f build/config.planetlab/yumexclude.pkgs ']' +++ pkgspath=build/config.planetlab/yumexclude.pkgs +++ break +++ '[' -z build/config.planetlab/yumexclude.pkgs ']' +++ echo 'pl_locateDistroFile: using build/config.planetlab/yumexclude.pkgs' pl_locateDistroFile: using build/config.planetlab/yumexclude.pkgs +++ echo build/config.planetlab/yumexclude.pkgs +++ return 0 ++ yumexclude_file=build/config.planetlab/yumexclude.pkgs ++ type -p pkgs.py ++ pkgs.py -a x86_64 -f f14 -d onelab plcyumexclude build/config.planetlab/yumexclude.pkgs + yumexclude=drupal + for repo in '$lxc_root/etc/yum.repos.d/*' + '[' -f /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/building.repo ']' + yumconf_exclude /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/building.repo exclude=drupal + repo=/vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/building.repo + shift + yumexclude_line=exclude=drupal + shift + sed -i -e '/#baseurl=.*$/i\ exclude=drupal' /vservers/2016.01.18--f14-n3-1-vplc01/etc/yum.repos.d/building.repo + cat + return 0 + '[' -d /vservers/2016.01.18--f14-n3-1-vplc01/cgroup ']' + mkdir /vservers/2016.01.18--f14-n3-1-vplc01/cgroup + '[' -h /vservers/2016.01.18--f14-n3-1-vplc01/etc/resolv.conf ']' + cp /etc/resolv.conf /vservers/2016.01.18--f14-n3-1-vplc01/etc/resolv.conf + '[' -f /vservers/2016.01.18--f14-n3-1-vplc01/etc/hosts ']' + mkdir -p /vservers/2016.01.18--f14-n3-1-vplc01/root/.ssh + cat /root/.ssh/id_rsa.pub + chmod 700 /vservers/2016.01.18--f14-n3-1-vplc01/root/.ssh + chmod 600 /vservers/2016.01.18--f14-n3-1-vplc01/root/.ssh/authorized_keys + config_xml=/tmp/2016.01.18--f14-n3-1-vplc01.xml + '[' -n '' ']' + write_lxc_xml_publicip 2016.01.18--f14-n3-1-vplc01 + lxc=2016.01.18--f14-n3-1-vplc01 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + cat + virsh -c lxc:/// define /tmp/2016.01.18--f14-n3-1-vplc01.xml setlocale: No such file or directory Domain 2016.01.18--f14-n3-1-vplc01 defined from /tmp/2016.01.18--f14-n3-1-vplc01.xml + return 0 + devel_or_test_tools 2016.01.18--f14-n3-1-vplc01 f14 onelab linux64 + set -x + set -e + trap failure ERR INT + lxc=2016.01.18--f14-n3-1-vplc01 + shift + fcdistro=f14 + shift + pldistro=onelab + shift + personality=linux64 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 ++ package_method f14 ++ fcdistro=f14 ++ shift ++ case $fcdistro in ++ echo yum + pkg_method=yum ++ pl_locateDistroFile build onelab runtime.pkgs ++ builddir=build ++ shift ++ pldistro=onelab ++ shift ++ pkgsfile=runtime.pkgs ++ shift ++ pkgspath= ++ configdir=build/config.onelab ++ '[' '!' -d build/config.onelab ']' ++ paths='build/config.onelab/runtime.pkgs build/config.planetlab/runtime.pkgs' ++ for path in '$paths' ++ '[' -f build/config.onelab/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 + pkgsfile=build/config.planetlab/runtime.pkgs ++ chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 uname -i + lxc_arch=x86_64 + '[' x86_64 = unknown ']' ++ pl_getPackages -a x86_64 f14 onelab build/config.planetlab/runtime.pkgs ++ pl_parsePkgs package -a x86_64 f14 onelab build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=package ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f14 ++ shift ++ pldistro=onelab ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f14 -d onelab package build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f14 -d onelab package build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f14 -d onelab package build/config.planetlab/runtime.pkgs + packages='bind-utils curl emacs-nox file openssh-clients yum-utils' ++ pl_getGroups -a x86_64 f14 onelab build/config.planetlab/runtime.pkgs ++ pl_parsePkgs group -a x86_64 f14 onelab build/config.planetlab/runtime.pkgs ++ target_arch=x86_64 ++ keyword=group ++ shift ++ '[' -a == -a ']' ++ shift ++ target_arch=x86_64 ++ shift ++ fcdistro=f14 ++ shift ++ pldistro=onelab ++ shift ++ echo 'pl_parsePkgs: using -a x86_64 -f f14 -d onelab group build/config.planetlab/runtime.pkgs' pl_parsePkgs: using -a x86_64 -f f14 -d onelab group build/config.planetlab/runtime.pkgs ++ pkgs.py -a x86_64 -f f14 -d onelab group build/config.planetlab/runtime.pkgs + groups= + case "$pkg_method" in + '[' -n 'bind-utils curl emacs-nox file openssh-clients yum-utils' ']' + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 yum -y install bind-utils curl emacs-nox file openssh-clients yum-utils Failed to set locale, defaulting to C Setting up Install Process Package curl-7.21.0-10.fc14.x86_64 already installed and latest version Package openssh-clients-5.5p1-24.fc14.2.x86_64 already installed and latest version Resolving Dependencies --> Running transaction check ---> Package bind-utils.x86_64 32:9.7.4-2.P1.fc14 set to be installed --> Processing Dependency: libdns.so.69()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 --> Processing Dependency: libisccc.so.60()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 --> Processing Dependency: libisc.so.62()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 --> Processing Dependency: libisccfg.so.62()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 --> Processing Dependency: liblwres.so.60()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 --> Processing Dependency: libbind9.so.60()(64bit) for package: 32:bind-utils-9.7.4-2.P1.fc14.x86_64 ---> Package emacs-nox.x86_64 1:23.2-7.fc14 set to be installed --> Processing Dependency: emacs-common = 1:23.2-7.fc14 for package: 1:emacs-nox-23.2-7.fc14.x86_64 --> Processing Dependency: libasound.so.2(ALSA_0.9.0rc4)(64bit) for package: 1:emacs-nox-23.2-7.fc14.x86_64 --> Processing Dependency: libasound.so.2(ALSA_0.9)(64bit) for package: 1:emacs-nox-23.2-7.fc14.x86_64 --> Processing Dependency: libasound.so.2()(64bit) for package: 1:emacs-nox-23.2-7.fc14.x86_64 ---> Package file.x86_64 0:5.04-17.fc14 set to be installed ---> Package yum-utils.noarch 0:1.1.28-1.fc14 set to be installed --> Running transaction check ---> Package alsa-lib.x86_64 0:1.0.24-1.fc14 set to be installed ---> Package bind-libs.x86_64 32:9.7.4-2.P1.fc14 set to be installed ---> Package emacs-common.x86_64 1:23.2-7.fc14 set to be installed --> Processing Dependency: perl >= 1:5 for package: 1:emacs-common-23.2-7.fc14.x86_64 --> Processing Dependency: /usr/bin/perl for package: 1:emacs-common-23.2-7.fc14.x86_64 --> Processing Dependency: perl(Getopt::Long) for package: 1:emacs-common-23.2-7.fc14.x86_64 --> Processing Dependency: perl(vars) for package: 1:emacs-common-23.2-7.fc14.x86_64 --> Processing Dependency: perl(strict) for package: 1:emacs-common-23.2-7.fc14.x86_64 --> Running transaction check ---> Package perl.x86_64 4:5.12.4-148.fc14 set to be installed --> Processing Dependency: perl-libs = 4:5.12.4-148.fc14 for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl(threads::shared) >= 1.21 for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl(threads::shared) for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl(Pod::Simple) for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl(threads) for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl-libs for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: perl(Module::Pluggable) for package: 4:perl-5.12.4-148.fc14.x86_64 --> Processing Dependency: libperl.so()(64bit) for package: 4:perl-5.12.4-148.fc14.x86_64 --> Running transaction check ---> Package perl-Module-Pluggable.noarch 1:3.90-148.fc14 set to be installed ---> Package perl-Pod-Simple.noarch 1:3.13-148.fc14 set to be installed --> Processing Dependency: perl(Pod::Escapes) >= 1.04 for package: 1:perl-Pod-Simple-3.13-148.fc14.noarch ---> Package perl-libs.x86_64 4:5.12.4-148.fc14 set to be installed ---> Package perl-threads.x86_64 0:1.81-1.fc14 set to be installed ---> Package perl-threads-shared.x86_64 0:1.32-148.fc14 set to be installed --> Running transaction check ---> Package perl-Pod-Escapes.noarch 1:1.04-148.fc14 set to be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: bind-utils x86_64 32:9.7.4-2.P1.fc14 updates 178 k emacs-nox x86_64 1:23.2-7.fc14 fedora 1.8 M file x86_64 5.04-17.fc14 updates 46 k yum-utils noarch 1.1.28-1.fc14 fedora 82 k Installing for dependencies: alsa-lib x86_64 1.0.24-1.fc14 updates 386 k bind-libs x86_64 32:9.7.4-2.P1.fc14 updates 845 k emacs-common x86_64 1:23.2-7.fc14 fedora 20 M perl x86_64 4:5.12.4-148.fc14 updates 11 M perl-Module-Pluggable noarch 1:3.90-148.fc14 updates 39 k perl-Pod-Escapes noarch 1:1.04-148.fc14 updates 32 k perl-Pod-Simple noarch 1:3.13-148.fc14 updates 211 k perl-libs x86_64 4:5.12.4-148.fc14 updates 595 k perl-threads x86_64 1.81-1.fc14 fedora 47 k perl-threads-shared x86_64 1.32-148.fc14 updates 52 k Transaction Summary ================================================================================ Install 14 Package(s) Total download size: 35 M Installed size: 122 M Downloading Packages: -------------------------------------------------------------------------------- Total 70 MB/s | 35 MB 00:00 warning: rpmts_HdrFromFdno: Header V3 RSA/SHA256 Signature, key ID 97a1071f: NOKEY Importing GPG key 0x97A1071F: Userid: "Fedora (14) " From : http://mirror.onelab.eu/keys//RPM-GPG-KEY-fedora-14-primary Running rpm_check_debug Running Transaction Test Transaction Test Succeeded Running Transaction Installing : 1:perl-Pod-Escapes-1.04-148.fc14.noarch 1/14 Installing : 1:perl-Module-Pluggable-3.90-148.fc14.noarch 2/14 Installing : 1:perl-Pod-Simple-3.13-148.fc14.noarch 3/14 Installing : perl-threads-shared-1.32-148.fc14.x86_64 4/14 Installing : perl-threads-1.81-1.fc14.x86_64 5/14 Installing : 4:perl-libs-5.12.4-148.fc14.x86_64 6/14 Installing : 4:perl-5.12.4-148.fc14.x86_64 7/14 Installing : 1:emacs-common-23.2-7.fc14.x86_64 8/14 Installing : alsa-lib-1.0.24-1.fc14.x86_64 9/14 Installing : 32:bind-libs-9.7.4-2.P1.fc14.x86_64 10/14 Installing : 32:bind-utils-9.7.4-2.P1.fc14.x86_64 11/14 Installing : 1:emacs-nox-23.2-7.fc14.x86_64 12/14 Installing : file-5.04-17.fc14.x86_64 13/14 Installing : yum-utils-1.1.28-1.fc14.noarch 14/14 Installed: bind-utils.x86_64 32:9.7.4-2.P1.fc14 emacs-nox.x86_64 1:23.2-7.fc14 file.x86_64 0:5.04-17.fc14 yum-utils.noarch 0:1.1.28-1.fc14 Dependency Installed: alsa-lib.x86_64 0:1.0.24-1.fc14 bind-libs.x86_64 32:9.7.4-2.P1.fc14 emacs-common.x86_64 1:23.2-7.fc14 perl.x86_64 4:5.12.4-148.fc14 perl-Module-Pluggable.noarch 1:3.90-148.fc14 perl-Pod-Escapes.noarch 1:1.04-148.fc14 perl-Pod-Simple.noarch 1:3.13-148.fc14 perl-libs.x86_64 4:5.12.4-148.fc14 perl-threads.x86_64 0:1.81-1.fc14 perl-threads-shared.x86_64 0:1.32-148.fc14 Complete! + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 rpm -aq + return 0 + post_install 2016.01.18--f14-n3-1-vplc01 linux64 + lxc=2016.01.18--f14-n3-1-vplc01 + shift + personality=linux64 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + cp /etc/localtime /vservers/2016.01.18--f14-n3-1-vplc01/etc/localtime + sshd_disable_password_auth 2016.01.18--f14-n3-1-vplc01 + lxc=2016.01.18--f14-n3-1-vplc01 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + sed --in-place=.password -e 's,^#\?PasswordAuthentication.*,PasswordAuthentication no,' /vservers/2016.01.18--f14-n3-1-vplc01/etc/ssh/sshd_config + '[' -n '' ']' + post_install_myplc 2016.01.18--f14-n3-1-vplc01 linux64 + set -x + set -e + trap failure ERR INT + lxc=2016.01.18--f14-n3-1-vplc01 + shift + personality=linux64 + shift ++ lxcroot 2016.01.18--f14-n3-1-vplc01 ++ lxc=2016.01.18--f14-n3-1-vplc01 ++ shift ++ echo /vservers/2016.01.18--f14-n3-1-vplc01 + lxc_root=/vservers/2016.01.18--f14-n3-1-vplc01 + cat + chroot /vservers/2016.01.18--f14-n3-1-vplc01 linux64 bash -x + '[' -f /etc/sysconfig/network ']' + /sbin/chkconfig crond off error reading information on service crond: Bad file descriptor + /bin/cat Starting guest 2016.01.18--f14-n3-1-vplc01 + '[' -n true ']' + echo Starting guest 2016.01.18--f14-n3-1-vplc01 + virsh -c lxc:/// start 2016.01.18--f14-n3-1-vplc01 setlocale: No such file or directory error: Failed to start domain 2016.01.18--f14-n3-1-vplc01 error: internal error: Failed to allocate free veth pair after 10 attempts ++ failure ++ echo 'lbuild-initvm.sh : Bailing out' lbuild-initvm.sh : Bailing out ++ exit 1 * 11:18:36 -- ********** 1 FAILED step plcvm_create on plctest1_vplc01 (discarded from further steps) TRACE: 1 11-18-20->15s=0:00:15.798331s status=KO step=plcvm_create plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step plc_install on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000118s status=UNDEF step=plc_install plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step plc_configure on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000062s status=UNDEF step=plc_configure plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step plc_start on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000070s status=UNDEF step=plc_start plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step keys_fetch on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=keys_fetch plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step keys_store on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=keys_store plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step keys_clear_known_hosts on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000056s status=UNDEF step=keys_clear_known_hosts plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step plcapi_urls on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=plcapi_urls plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step speed_up_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=speed_up_slices plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step initscripts on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=initscripts plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sites on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=sites plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step nodes on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000076s status=UNDEF step=nodes plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=slices plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step nodegroups on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=nodegroups plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step leases on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=leases plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step check_vsys_defaults_ignore on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=check_vsys_defaults_ignore plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_kill_mine on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=qemu_kill_mine plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step nodestate_reinstall on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=nodestate_reinstall plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_local_init on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=qemu_local_init plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step bootcd on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=bootcd plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_local_config on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_local_config plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_clean_mine on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_clean_mine plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_export on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_export plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_cleanlog on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_cleanlog plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_start on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=qemu_start plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_timestamp on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_timestamp plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step qemu_nodefamily on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=qemu_nodefamily plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_install_all on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000057s status=UNDEF step=sfa_install_all plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_configure on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_configure plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step cross_sfa_configure on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=cross_sfa_configure plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_start on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_start plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_import on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_import plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfi_configure on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000058s status=UNDEF step=sfi_configure plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_register_site on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=sfa_register_site plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_register_pi on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_register_pi plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_register_user on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000064s status=UNDEF step=sfa_register_user plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_update_user on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_update_user plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_register_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_register_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_renew_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000057s status=UNDEF step=sfa_renew_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_remove_user_from_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=sfa_remove_user_from_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfi_show_slice_researchers on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_insert_user_in_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_insert_user_in_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfi_show_slice_researchers on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_discover on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000056s status=UNDEF step=sfa_discover plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_rspec on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_rspec plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_allocate on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_allocate plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_provision on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_provision plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_describe on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_describe plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_check_slice_plc on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_check_slice_plc plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_update_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_update_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfi_list on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfi_list plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfi_show_site on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000065s status=UNDEF step=sfi_show_site plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_utest on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=sfa_utest plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step probe_kvm_iptables on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=probe_kvm_iptables plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ping_node on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ping_node plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_node_debug on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=ssh_node_debug plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step plcsh_stress_test on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=plcsh_stress_test plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_node_boot on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_node_boot plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step node_bmlogs on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=node_bmlogs plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000064s status=UNDEF step=ssh_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_slice_basics on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_basics plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step check_initscripts on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=check_initscripts plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_slice_sfa on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_sfa plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_rspec_empty on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_rspec_empty plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_allocate_empty on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_allocate_empty plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_provision_empty on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=sfa_provision_empty plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_check_slice_plc_empty on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_check_slice_plc_empty plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_delete_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_delete_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step sfa_delete_user on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=sfa_delete_user plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step cross_check_tcp on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=cross_check_tcp plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step check_system_slice on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=check_system_slice plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step debug_nodemanager on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000058s status=UNDEF step=debug_nodemanager plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step empty_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=empty_slices plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_slice_off on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_off plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step slice_fs_deleted_ignore on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step fill_slices on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=fill_slices plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 SKIPPED Step ssh_slice_again on plctest1_vplc01 (has failed plcvm_create) TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=ssh_slice_again plc=plctest1_vplc01 force=False * 11:18:36 -- ********** 1 RUNNING step gather_logs (forced after plcvm_create has failed) on plc plctest1_vplc01 -------------------- TestPlc.gather_logs : PLC's /var/log -> 11:18:36 -- + mkdir -p logs/myplc.var-log.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01 -> 11:18: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 /var/log/ -cf - . + tar -C logs/myplc.var-log.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. ssh: connect to host vplc01 port 22: No route to host tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -> 11:18:40 -- + chmod a+r,a+x logs/myplc.var-log.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01/httpd chmod: cannot access 'logs/myplc.var-log.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01/httpd': No such file or directory -------------------- TestPlc.gather_logs : PLC's /var/lib/psql/data/pg_log/ -> 11:18:40 -- + mkdir -p logs/myplc.pgsql-log.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01 -> 11:18:40 -- + 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.2016.01.18--f14-n3-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. ssh: connect to host vplc01 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/ -> 11:18:43 -- + mkdir -p logs/sfi.plctest1_vplc01.2016.01.18--f14-n3-1-vplc01 -> 11:18:43 -- + 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.2016.01.18--f14-n3-1-vplc01 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. ssh: connect to host vplc01 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 -> 11:18:46 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 boxtops:/vservers/2016.01.18--f14-n3/qemu-vnode04.pl.sophia.inria.fr/log.txt logs/node.qemu.vnode04.pl.sophia.inria.fr.txt scp: /vservers/2016.01.18--f14-n3/qemu-vnode04.pl.sophia.inria.fr/log.txt: No such file or directory -------------------- TestPlc.gather_logs : nodes's /var/log -> 11:18:46 -- + mkdir -p logs/node.var-log.vnode04.pl.sophia.inria.fr -> 11:18:46 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode04.pl.sophia.inria.fr tar -C /var/log -cf - . + tar -C logs/node.var-log.vnode04.pl.sophia.inria.fr -xf - Warning: Identity file keys/key_admin.rsa not accessible: No such file or directory. ssh: connect to host vnode04.pl.sophia.inria.fr port 22: Connection timed out 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/2016.01.18--f14-n3/TestPlc.py", line 1859, in gather_logs self.gather_slivers_var_logs() File "/root/2016.01.18--f14-n3/TestPlc.py", line 1863, in gather_slivers_var_logs for test_sliver in self.all_sliver_objs(): File "/root/2016.01.18--f14-n3/TestPlc.py", line 412, in all_sliver_objs result.append(self.locate_sliver_obj(nodename, slicename)) File "/root/2016.01.18--f14-n3/TestPlc.py", line 423, in locate_sliver_obj return TestSliver(self, test_node, test_slice) File "/root/2016.01.18--f14-n3/TestSliver.py", line 16, in __init__ self.test_ssh = self.create_test_ssh() File "/root/2016.01.18--f14-n3/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 * 11:18:51 -- ********** 1 FAILED (exception) step gather_logs on plctest1_vplc01 (discarded from further steps) TRACE: 1 11-18-36->14s=0:00:14.618719s status=KO step=gather_logs plc=plctest1_vplc01 force=True TestMain exit code 1